-- Logs begin at Thu 2024-04-18 22:34:53 JST, end at Thu 2024-04-18 23:13:43 JST. -- Apr 18 23:12:00 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:00 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:00 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:00 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:00 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:00 volumio volumio[812]: info: Apr 18 23:12:00 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:00 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:00 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:00 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:00 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:00 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:00 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:00 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:00 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:00 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:00 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:00 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:00 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3365,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2628 Kbps","isStreaming":false,"title":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:00 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:00 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:00 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:00 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:00 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:00 volumio volumio[812]: info: ------------------------------ 27ms Apr 18 23:12:02 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:02 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:02 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011) Apr 18 23:12:02 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011) in service mpd Apr 18 23:12:02 volumio volumio[812]: info: Apr 18 23:12:02 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F01.%20Sunny%20Days.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F02.%20Tea%20For%20Two.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/02. Tea For Two.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F03.%20Samois%20%C3%80%20Moi.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/03. Samois À Moi.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F04.%20Willow%20Weep%20For%20Me.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/04. Willow Weep For Me.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F05.%20Just%20The%20Two%20Of%20Us.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/05. Just The Two Of Us.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F06.%20Invitation.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/06. Invitation.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F07.%20Midnight%20Sun.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/07. Midnight Sun.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F08.%20Morena%20Boca%20De%20Ouro.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/08. Morena Boca De Ouro.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F09.%20Je%20Suis%20Seule%20Ce%20Soir.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/09. Je Suis Seule Ce Soir.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F10.%20Dos%20Gardenias.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/10. Dos Gardenias.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F11.%20Paper%20Moon.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/11. Paper Moon.flac Apr 18 23:12:02 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Cyrille%20Aim%C3%A9e%20%26%20Diego%20Figueiredo/Just%20The%20Two%20Of%20Us/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FCyrille%20Aimee%20%26%20Diego%20Figueiredo%20-%20Just%20The%20Two%20Of%20Us%20(2011)%2F12.%20Samba%20Em%20Preludio.flac&metadata=false Apr 18 23:12:02 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/12. Samba Em Preludio.flac Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand stop took 156 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand status took 24 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:02 volumio volumio[812]: info: Apr 18 23:12:02 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:02 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand clear took 3 milliseconds Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac" Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: 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":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:02 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: No code Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: ------------------------------ 57ms Apr 18 23:12:02 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:02 volumio volumio[812]: info: Apr 18 23:12:02 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:02 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:02 volumio volumio[812]: info: ------------------------------ 36ms Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac" took 35 milliseconds Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:02 volumio volumio[812]: info: ------------------------------ 5ms Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand play took 4 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: Apr 18 23:12:02 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:02 volumio volumio[812]: info: Apr 18 23:12:02 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand status took 44 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand status took 15 milliseconds Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sunny Days","artist":"Cyrille Aimée & Diego Figueiredo","album":"Just The Two Of Us","uri":"NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac","trackType":"flac"} Apr 18 23:12:02 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:02 volumio volumio[812]: info: ------------------------------ 81ms Apr 18 23:12:02 volumio volumio[812]: info: sendMpdCommand playlistinfo took 70 milliseconds Apr 18 23:12:02 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:02 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":247,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1600 Kbps","isStreaming":false,"title":"Sunny Days","artist":"Cyrille Aimée & Diego Figueiredo","album":"Just The Two Of Us","uri":"NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac","trackType":"flac"} Apr 18 23:12:02 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:02 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:02 volumio volumio[812]: info: ------------------------------ 193ms Apr 18 23:12:04 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:04 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:04 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:04 volumio volumio[812]: info: Apr 18 23:12:04 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:04 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:04 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:04 volumio volumio[812]: info: sendMpdCommand status took 0 milliseconds Apr 18 23:12:04 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:04 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:04 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:04 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:04 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:04 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2488,"duration":247,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1587 Kbps","isStreaming":false,"title":"Sunny Days","artist":"Cyrille Aimée & Diego Figueiredo","album":"Just The Two Of Us","uri":"NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac","trackType":"flac"} Apr 18 23:12:04 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:04 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:04 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:04 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:04 volumio volumio[812]: info: ------------------------------ 14ms Apr 18 23:12:09 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:09 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:09 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Coleman Hawkins Apr 18 23:12:09 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Coleman Hawkins in service mpd Apr 18 23:12:09 volumio volumio[812]: info: Apr 18 23:12:09 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Coleman%20Hawkins/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F1.%20Crazy%20Rhythm.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Lester%20Young/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F10.%20Afternoon%20of%20a%20Basie-Ite.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/10. Afternoon of a Basie-Ite.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Lester%20Young/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F11.%20Sometimes%20I'm%20Happy.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/11. Sometimes I'm Happy.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Lester%20Young/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F12.%20I%20Never%20Knew.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/12. I Never Knew.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Don%20Byas/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F13.%20Laura.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/13. Laura.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Don%20Byas/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F14.%20Slam%2C%20Don't%20Shake%20Like%20That.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/14. Slam, Don't Shake Like That.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Don%20Byas/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F15.%20Stardust.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/15. Stardust.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Don%20Byas/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F16.%20Dark%20Eyes.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/16. Dark Eyes.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Coleman%20Hawkins/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F2.%20Get%20Happy.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/2. Get Happy.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Coleman%20Hawkins/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F3.%20How%20Deep%20the%20Ocean.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/3. How Deep the Ocean.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Coleman%20Hawkins/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F4.%20Lover%20Come%20Back%20to%20Me.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/4. Lover Come Back to Me.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Chu%20Berry/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F5.%2046W%2C%2052nd.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/5. 46W, 52nd.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Chu%20Berry/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F6.%20Monday%20at%20Minton's.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/6. Monday at Minton's.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Chu%20Berry/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F7.%20Ghost%20of%20a%20Chance.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/7. Ghost of a Chance.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Chu%20Berry/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F8.%20Special%20Delivery.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/8. Special Delivery.flac Apr 18 23:12:09 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Lester%20Young/The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FColeman%20Hawkins%2FColeman%20Hawkins%2C%20Chu%20Berry%2C%20Lester%20Young%2C%20Don%20Byas%20-%20The%20Best%20of%20Swing%20Jazz%20-%20Tenor%20Sax%20(2023)%2F9.%20Just%20You%2C%20Just%20Me.flac&metadata=false Apr 18 23:12:09 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/9. Just You, Just Me.flac Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand stop took 72 milliseconds Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand status took 13 milliseconds Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:09 volumio volumio[812]: info: Apr 18 23:12:09 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:09 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand clear took 4 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac" Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: 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":"Sunny Days","artist":"Cyrille Aimée & Diego Figueiredo","album":"Just The Two Of Us","uri":"NAS/LS510/_Evaluating/Cyrille Aimee & Diego Figueiredo - Just The Two Of Us (2011)/01. Sunny Days.flac","trackType":"flac"} Apr 18 23:12:09 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: No code Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: ------------------------------ 57ms Apr 18 23:12:09 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:09 volumio volumio[812]: info: Apr 18 23:12:09 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:09 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:09 volumio volumio[812]: info: ------------------------------ 32ms Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac" took 31 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:09 volumio volumio[812]: info: ------------------------------ 10ms Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand play took 9 milliseconds Apr 18 23:12:09 volumio volumio[812]: info: Apr 18 23:12:09 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand status took 38 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:09 volumio volumio[812]: info: Apr 18 23:12:09 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy Rhythm","artist":"Coleman Hawkins","album":"The Best of Swing Jazz - Tenor Sax","uri":"NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac","trackType":"flac"} Apr 18 23:12:09 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:09 volumio volumio[812]: info: ------------------------------ 45ms Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand status took 4 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:09 volumio volumio[812]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 23:12:09 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:09 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crazy Rhythm","artist":"Coleman Hawkins","album":"The Best of Swing Jazz - Tenor Sax","uri":"NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac","trackType":"flac"} Apr 18 23:12:09 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:09 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:09 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:09 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:09 volumio volumio[812]: info: ------------------------------ 31ms Apr 18 23:12:11 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:11 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:11 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:11 volumio volumio[812]: info: Apr 18 23:12:11 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:11 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:11 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:11 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:11 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:11 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:11 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:11 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:11 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:11 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2731,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1516 Kbps","isStreaming":false,"title":"Crazy Rhythm","artist":"Coleman Hawkins","album":"The Best of Swing Jazz - Tenor Sax","uri":"NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac","trackType":"flac"} Apr 18 23:12:11 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:11 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:11 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:11 volumio volumio[812]: info: ------------------------------ 15ms Apr 18 23:12:13 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:13 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:13 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993) Apr 18 23:12:13 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993) in service mpd Apr 18 23:12:13 volumio volumio[812]: info: Apr 18 23:12:13 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F01.%20South%20Of%20The%20Border.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F02.%20Star%20Crossed%20Lovers.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/02. Star Crossed Lovers.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F03.%20Robbin's%20Nest.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/03. Robbin's Nest.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F04.%20Embraceable%20You.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/04. Embraceable You.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F05.%20Pretendo.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/05. Pretendo.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F06.%20Corcovado.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/06. Corcovado.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F07.%20As%20Time%20Goes%20By.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/07. As Time Goes By.flac Apr 18 23:12:13 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/South%20Of%20The%20Border%20West%20Of%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20South%20Of%20The%20Border%20West%20Of%20The%20Sun%20(1993)%2F08.%20West%20Of%20The%20Sun.flac&metadata=false Apr 18 23:12:13 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/08. West Of The Sun.flac Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand stop took 130 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand status took 11 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand stop took 3 milliseconds Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:13 volumio volumio[812]: info: Apr 18 23:12:13 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:13 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand clear took 4 milliseconds Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac" Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: 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":"Crazy Rhythm","artist":"Coleman Hawkins","album":"The Best of Swing Jazz - Tenor Sax","uri":"NAS/LS510/_Evaluating/Coleman Hawkins/Coleman Hawkins, Chu Berry, Lester Young, Don Byas - The Best of Swing Jazz - Tenor Sax (2023)/1. Crazy Rhythm.flac","trackType":"flac"} Apr 18 23:12:13 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: No code Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: ------------------------------ 51ms Apr 18 23:12:13 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:13 volumio volumio[812]: info: Apr 18 23:12:13 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:13 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:13 volumio volumio[812]: info: ------------------------------ 33ms Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac" took 31 milliseconds Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:13 volumio volumio[812]: info: ------------------------------ 11ms Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand play took 11 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: Apr 18 23:12:13 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:13 volumio volumio[812]: info: Apr 18 23:12:13 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand status took 44 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand status took 4 milliseconds Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":393,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Of The Border","artist":"Claude Williamson Trio","album":"South Of The Border West Of The Sun","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac","trackType":"flac"} Apr 18 23:12:13 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:13 volumio volumio[812]: info: ------------------------------ 78ms Apr 18 23:12:13 volumio volumio[812]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 18 23:12:13 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:13 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":393,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1667 Kbps","isStreaming":false,"title":"South Of The Border","artist":"Claude Williamson Trio","album":"South Of The Border West Of The Sun","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac","trackType":"flac"} Apr 18 23:12:13 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:13 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:13 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:13 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:13 volumio volumio[812]: info: ------------------------------ 91ms Apr 18 23:12:15 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:15 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:15 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:15 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:15 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:15 volumio volumio[812]: info: Apr 18 23:12:15 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:15 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:15 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:15 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:15 volumio volumio[812]: info: sendMpdCommand status took 2 milliseconds Apr 18 23:12:15 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:15 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:15 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:15 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:15 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:15 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:15 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:15 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3107,"duration":393,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1708 Kbps","isStreaming":false,"title":"South Of The Border","artist":"Claude Williamson Trio","album":"South Of The Border West Of The Sun","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac","trackType":"flac"} Apr 18 23:12:15 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:15 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:15 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:15 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:15 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:15 volumio volumio[812]: info: ------------------------------ 17ms Apr 18 23:12:17 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:17 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:17 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994) Apr 18 23:12:17 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994) in service mpd Apr 18 23:12:17 volumio volumio[812]: info: Apr 18 23:12:17 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F01.%20Se%C3%B1or%20Blues.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F02.%20Poinciana.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/02. Poinciana.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F03.%20Estrellita.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/03. Estrellita.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F04.%20Barbados.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/04. Barbados.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F05.%20El%20Noche%20De%20Espa%C3%B1a.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/05. El Noche De España.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F06.%20Besame%20Mucho.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/06. Besame Mucho.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F07.%20Smoke%20Gets%20In%20Your%20Eyes.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/07. Smoke Gets In Your Eyes.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F08.%20Estate.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/08. Estate.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F09.%20I%20Heard%20you%20Cried%20Last%20Night.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/09. I Heard you Cried Last Night.flac Apr 18 23:12:17 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Claude%20Williamson%20Trio/El%20Noche%20De%20Espa%C3%B1a/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FClaude%20Williamson%20Trio%20-%20El%20Noche%20De%20Espana%20(1994)%2F10.%20Nightingale%20In%20Berkeley%20Square.flac&metadata=false Apr 18 23:12:17 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/10. Nightingale In Berkeley Square.flac Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand stop took 69 milliseconds Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand status took 10 milliseconds Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand stop took 1 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:17 volumio volumio[812]: info: Apr 18 23:12:17 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:17 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand clear took 3 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac" Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: 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":"South Of The Border","artist":"Claude Williamson Trio","album":"South Of The Border West Of The Sun","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - South Of The Border West Of The Sun (1993)/01. South Of The Border.flac","trackType":"flac"} Apr 18 23:12:17 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: No code Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: ------------------------------ 38ms Apr 18 23:12:17 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:17 volumio volumio[812]: info: Apr 18 23:12:17 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:17 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:17 volumio volumio[812]: info: ------------------------------ 33ms Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac" took 31 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:17 volumio volumio[812]: info: ------------------------------ 10ms Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand play took 9 milliseconds Apr 18 23:12:17 volumio volumio[812]: info: Apr 18 23:12:17 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:17 volumio volumio[812]: info: Apr 18 23:12:17 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand status took 8 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand status took 4 milliseconds Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":334,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Señor Blues","artist":"Claude Williamson Trio","album":"El Noche De España","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac","trackType":"flac"} Apr 18 23:12:17 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:17 volumio volumio[812]: info: ------------------------------ 21ms Apr 18 23:12:17 volumio volumio[812]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 18 23:12:17 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:17 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":334,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Señor Blues","artist":"Claude Williamson Trio","album":"El Noche De España","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac","trackType":"flac"} Apr 18 23:12:17 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:17 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:17 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:17 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:17 volumio volumio[812]: info: ------------------------------ 66ms Apr 18 23:12:19 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:19 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:19 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:19 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:19 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:19 volumio volumio[812]: info: Apr 18 23:12:19 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:19 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:19 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:19 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:19 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:19 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:19 volumio volumio[812]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 23:12:19 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:19 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:19 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:19 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2603,"duration":334,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1537 Kbps","isStreaming":false,"title":"Señor Blues","artist":"Claude Williamson Trio","album":"El Noche De España","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac","trackType":"flac"} Apr 18 23:12:19 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:19 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:19 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:19 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:19 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:19 volumio volumio[812]: info: ------------------------------ 15ms Apr 18 23:12:23 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:23 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:23 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022) Apr 18 23:12:23 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022) in service mpd Apr 18 23:12:23 volumio volumio[812]: info: Apr 18 23:12:23 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand stop took 137 milliseconds Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F01%20-%20Heartbeat.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F02%20-%20Heaven%2C%20Wait.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/02 - Heaven, Wait.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F03%20-%20Don't%20Know%20Why.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/03 - Don't Know Why.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F04%20-%20Blackbirds.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/04 - Blackbirds.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F05%20-%20A%20Different%20Kind%20of%20Love.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/05 - A Different Kind of Love.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F06%20-%20Clay.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/06 - Clay.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F07%20-%20Carry%20Me.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/07 - Carry Me.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F08%20-%20Play%20Dead.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/08 - Play Dead.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F09%20-%20Green%20Book.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/09 - Green Book.flac Apr 18 23:12:23 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Ghostly%20Kisses/Heaven%2C%20Wait/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FGhostly%20Kisses%20-%20Heaven%2C%20Wait%20(2022)%2F10%20-%20Your%20Heart%20is%20Gold.flac&metadata=false Apr 18 23:12:23 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/10 - Your Heart is Gold.flac Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand status took 7 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: 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":"Señor Blues","artist":"Claude Williamson Trio","album":"El Noche De España","uri":"NAS/LS510/_Evaluating/Claude Williamson Trio - El Noche De Espana (1994)/01. Señor Blues.flac","trackType":"flac"} Apr 18 23:12:23 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: No code Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: ------------------------------ 32ms Apr 18 23:12:23 volumio volumio[812]: info: Apr 18 23:12:23 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:23 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand clear took 31 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac" Apr 18 23:12:23 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:23 volumio volumio[812]: info: Apr 18 23:12:23 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:23 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:23 volumio volumio[812]: info: ------------------------------ 2ms Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac" took 2 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:23 volumio volumio[812]: info: ------------------------------ 10ms Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand play took 9 milliseconds Apr 18 23:12:23 volumio volumio[812]: info: Apr 18 23:12:23 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:23 volumio volumio[812]: info: Apr 18 23:12:23 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand status took 41 milliseconds Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heartbeat","artist":"Ghostly Kisses","album":"Heaven, Wait","uri":"NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac","trackType":"flac"} Apr 18 23:12:23 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:23 volumio volumio[812]: info: ------------------------------ 50ms Apr 18 23:12:23 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:23 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:23 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1564 Kbps","isStreaming":false,"title":"Heartbeat","artist":"Ghostly Kisses","album":"Heaven, Wait","uri":"NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac","trackType":"flac"} Apr 18 23:12:23 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:23 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:23 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:23 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:23 volumio volumio[812]: info: ------------------------------ 56ms Apr 18 23:12:25 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:25 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:25 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:25 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:25 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:25 volumio volumio[812]: info: Apr 18 23:12:25 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:25 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:25 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:25 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:25 volumio volumio[812]: info: sendMpdCommand status took 2 milliseconds Apr 18 23:12:25 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:25 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:25 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:25 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:25 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:25 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:25 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:25 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2857,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1613 Kbps","isStreaming":false,"title":"Heartbeat","artist":"Ghostly Kisses","album":"Heaven, Wait","uri":"NAS/LS510/_Evaluating/Ghostly Kisses - Heaven, Wait (2022)/01 - Heartbeat.flac","trackType":"flac"} Apr 18 23:12:25 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:25 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:25 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:25 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:25 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:25 volumio volumio[812]: info: ------------------------------ 17ms Apr 18 23:12:28 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:28 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:28 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Gerald Wilson Apr 18 23:12:28 volumio volumio[812]: info: Using cached record of: music-library/NAS/LS510/_Evaluating/Gerald Wilson Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:28 volumio volumio[812]: info: Apr 18 23:12:28 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand stop took 24 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand stop took 6 milliseconds Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:28 volumio volumio[812]: info: Apr 18 23:12:28 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:28 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand status took 5 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand clear took 4 milliseconds Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac" Apr 18 23:12:28 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:28 volumio volumio[812]: info: Apr 18 23:12:28 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:28 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:28 volumio volumio[812]: info: ------------------------------ 4ms Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac" took 3 milliseconds Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:28 volumio volumio[812]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 23:12:28 volumio volumio[812]: info: ------------------------------ 11ms Apr 18 23:12:28 volumio volumio[812]: info: ------------------------------ 7ms Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand play took 5 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: Apr 18 23:12:28 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:28 volumio volumio[812]: info: Apr 18 23:12:28 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand status took 40 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:28 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:28 volumio volumio[812]: info: ------------------------------ 50ms Apr 18 23:12:28 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:12:28 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:28 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:28 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:28 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:28 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:28 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:28 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:28 volumio volumio[812]: info: ------------------------------ 33ms Apr 18 23:12:30 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:30 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:30 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:30 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:30 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:30 volumio volumio[812]: info: Apr 18 23:12:30 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:30 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:30 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:30 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:30 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:30 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:30 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:30 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:30 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:30 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:30 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:30 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:30 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2367,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2647 Kbps","isStreaming":false,"title":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:30 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:30 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:30 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:30 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:30 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:30 volumio volumio[812]: info: ------------------------------ 15ms Apr 18 23:12:31 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:31 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:31 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/One For All Apr 18 23:12:31 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/One For All in service mpd Apr 18 23:12:31 volumio volumio[812]: info: Apr 18 23:12:31 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F01.%20The%20End%20Of%20A%20Love%20Affair.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F02.%20Stolen%20Moments.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/02. Stolen Moments.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F03.%20Corcovado.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/03. Corcovado.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F04.%20How%20Are%20You.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/04. How Are You.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F05.%20Shinjuku%20Waltz.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/05. Shinjuku Waltz.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F06.%20Skylark.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/06. Skylark.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F07.%20The%20Eyes%20Have%20It.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/07. The Eyes Have It.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/The%20End%20Of%20A%20Love%20Affair/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FThe%20End%20Of%20A%20Love%20Affair%2F08.%20Street%20Of%20Dreams.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/08. Street Of Dreams.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F01.%20What's%20Going%20On.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/01. What's Going On.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F02.%20How%20Sweet%20It%20Is%20(%20To%20Be%20Loved%20By%20You%20).flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/02. How Sweet It Is ( To Be Loved By You ).flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F03.%20Let's%20Stay%20Together.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/03. Let's Stay Together.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F04.%20Reasons.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/04. Reasons.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F05.%20Golden%20Lady.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/05. Golden Lady.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F06.%20For%20The%20Love%20Of%20You.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/06. For The Love Of You.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F07.%20Stop!%20In%20The%20Name%20Of%20Love.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/07. Stop! In The Name Of Love.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F08.%20Moon%20Blue.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/08. Moon Blue.flac Apr 18 23:12:31 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=One%20For%20All/What's%20Going%20On%3F/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FOne%20For%20All%2FWhat's%20Going%20On%2F09.%20Betcha%20By%20Golly%2C%20Wow.flac&metadata=false Apr 18 23:12:31 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/One For All/What's Going On/09. Betcha By Golly, Wow.flac Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand stop took 181 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand status took 16 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:31 volumio volumio[812]: info: Apr 18 23:12:31 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:31 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand clear took 5 milliseconds Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac" Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: 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":"Bugle Call Rag","artist":"Wilbert Baranco","album":"Groovin' High","uri":"NAS/LS510/_Evaluating/Gerald Wilson/Groovin' High/1. Bugle Call Rag.flac","trackType":"flac"} Apr 18 23:12:31 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: No code Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: ------------------------------ 63ms Apr 18 23:12:31 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:31 volumio volumio[812]: info: Apr 18 23:12:31 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:31 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:31 volumio volumio[812]: info: ------------------------------ 31ms Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac" took 29 milliseconds Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:31 volumio volumio[812]: info: ------------------------------ 11ms Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand play took 10 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: Apr 18 23:12:31 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:31 volumio volumio[812]: info: Apr 18 23:12:31 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand status took 39 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The End Of A Love Affair","artist":"One For All","album":"The End Of A Love Affair","uri":"NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac","trackType":"flac"} Apr 18 23:12:31 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:31 volumio volumio[812]: info: ------------------------------ 50ms Apr 18 23:12:31 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:12:31 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:31 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The End Of A Love Affair","artist":"One For All","album":"The End Of A Love Affair","uri":"NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac","trackType":"flac"} Apr 18 23:12:31 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:31 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:31 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:31 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:31 volumio volumio[812]: info: ------------------------------ 34ms Apr 18 23:12:33 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:33 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:33 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:33 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:33 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:33 volumio volumio[812]: info: Apr 18 23:12:33 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:33 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:33 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:33 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:33 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:33 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:33 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:33 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:12:33 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:33 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:33 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:33 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:33 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2488,"duration":455,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1766 Kbps","isStreaming":false,"title":"The End Of A Love Affair","artist":"One For All","album":"The End Of A Love Affair","uri":"NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac","trackType":"flac"} Apr 18 23:12:33 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:33 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:33 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:33 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:33 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:33 volumio volumio[812]: info: ------------------------------ 17ms Apr 18 23:12:37 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:37 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:37 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957) Apr 18 23:12:37 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957) in service mpd Apr 18 23:12:37 volumio volumio[812]: info: Apr 18 23:12:37 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F01.%20Mamma-Daddy%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F02.%20Body%20And%20Soul%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/02. Body And Soul (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F03.%20Desert%20Sands%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/03. Desert Sands (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F04.%2031st%20And%20State%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/04. 31st And State (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F05.%20Lullaby%20Of%20The%20Leaves%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/05. Lullaby Of The Leaves (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F06.%20Latin%20Quarters%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/06. Latin Quarters (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F07.%20Be-Ware%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/07. Be-Ware (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Wilbur%20Ware/The%20Chicago%20Sound/extralarge&path=%2Fmnt%2FNAS%2FLS510%2F_Evaluating%2FWilbur%20Ware%20-%20The%20Chicago%20Sound%20(Remaster%202023)%20(1957)%2F08.%20The%20Man%20I%20Love%20(Remastered%202023).flac&metadata=false Apr 18 23:12:37 volumio volumio[812]: info: URI /mnt/NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/08. The Man I Love (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand stop took 64 milliseconds Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand status took 9 milliseconds Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand stop took 1 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:37 volumio volumio[812]: info: Apr 18 23:12:37 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:37 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand clear took 2 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac" Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: 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 End Of A Love Affair","artist":"One For All","album":"The End Of A Love Affair","uri":"NAS/LS510/_Evaluating/One For All/The End Of A Love Affair/01. The End Of A Love Affair.flac","trackType":"flac"} Apr 18 23:12:37 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: No code Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: ------------------------------ 31ms Apr 18 23:12:37 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:37 volumio volumio[812]: info: Apr 18 23:12:37 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:37 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:37 volumio volumio[812]: info: ------------------------------ 32ms Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac" took 30 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:37 volumio volumio[812]: info: ------------------------------ 9ms Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand play took 9 milliseconds Apr 18 23:12:37 volumio volumio[812]: info: Apr 18 23:12:37 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand status took 39 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:37 volumio volumio[812]: info: Apr 18 23:12:37 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mamma-Daddy","artist":"Wilbur Ware","album":"The Chicago Sound","uri":"NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac","trackType":"flac"} Apr 18 23:12:37 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:37 volumio volumio[812]: info: ------------------------------ 47ms Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand status took 5 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:37 volumio volumio[812]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 23:12:37 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:37 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mamma-Daddy","artist":"Wilbur Ware","album":"The Chicago Sound","uri":"NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac","trackType":"flac"} Apr 18 23:12:37 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:37 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:37 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:37 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:37 volumio volumio[812]: info: ------------------------------ 35ms Apr 18 23:12:40 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:12:40 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:40 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:12:40 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:12:40 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:12:40 volumio volumio[812]: info: Apr 18 23:12:40 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:40 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:12:40 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:40 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:40 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:12:40 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:40 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:40 volumio volumio[812]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 23:12:40 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:40 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:40 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:40 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:40 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2982,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1254 Kbps","isStreaming":false,"title":"Mamma-Daddy","artist":"Wilbur Ware","album":"The Chicago Sound","uri":"NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac","trackType":"flac"} Apr 18 23:12:40 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:40 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:40 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:40 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:40 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:40 volumio volumio[812]: info: ------------------------------ 16ms Apr 18 23:12:43 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 23:12:43 volumio volumio[812]: info: CURURI: music-library/NAS/LS510/PCM Apr 18 23:12:44 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:58 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:58 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:58 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:58 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:12:58 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:12:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:58 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:12:58 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:12:58 volumio volumio[812]: info: Preload queue cleared Apr 18 23:12:58 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/PCM/Dar Williams Apr 18 23:12:58 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/PCM/Dar Williams in service mpd Apr 18 23:12:59 volumio volumio[812]: info: Apr 18 23:12:59 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand stop took 80 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F01.%20Mercy%20Of%20The%20Fallen.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F02.%20Farewell%20To%20The%20Old%20Me.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/02. Farewell To The Old Me.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F03.%20I%20Saw%20A%20Bird%20Fly%20Away.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/03. I Saw A Bird Fly Away.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F04.%20The%20Beauty%20Of%20The%20Rain.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/04. The Beauty Of The Rain.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F05.%20The%20World's%20Not%20Falling%20Apart.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/05. The World's Not Falling Apart.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F06.%20The%20One%20Who%20Knows.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/06. The One Who Knows.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F07.%20Closer%20To%20Me.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/07. Closer To Me.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F08.%20Fishing%20In%20The%20Morning.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/08. Fishing In The Morning.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F09.%20Whispering%20Pines.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/09. Whispering Pines.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F10.%20Your%20Fire%20Your%20Soul.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/10. Your Fire Your Soul.flac Apr 18 23:12:59 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Dar%20Williams/The%20Beauty%20of%20The%20Rain/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FDar%20Williams%2FThe%20Beauty%20of%20The%20Rain%2F11.%20I%20Have%20Lost%20My%20Dreams.flac&metadata=false Apr 18 23:12:59 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/11. I Have Lost My Dreams.flac Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand status took 17 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand stop took 1 milliseconds Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:12:59 volumio volumio[812]: info: Apr 18 23:12:59 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:59 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand clear took 2 milliseconds Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac" Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: 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":"Mamma-Daddy","artist":"Wilbur Ware","album":"The Chicago Sound","uri":"NAS/LS510/_Evaluating/Wilbur Ware - The Chicago Sound (Remaster 2023) (1957)/01. Mamma-Daddy (Remastered 2023).flac","trackType":"flac"} Apr 18 23:12:59 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:59 volumio volumio[812]: info: No code Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:59 volumio volumio[812]: info: ------------------------------ 35ms Apr 18 23:12:59 volumio volumio[812]: error: updateQueue error: null Apr 18 23:12:59 volumio volumio[812]: info: Apr 18 23:12:59 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:12:59 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:12:59 volumio volumio[812]: info: ------------------------------ 35ms Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac" took 33 milliseconds Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:12:59 volumio volumio[812]: info: ------------------------------ 10ms Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand play took 10 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: Apr 18 23:12:59 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:59 volumio volumio[812]: info: Apr 18 23:12:59 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand status took 40 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mercy of The Fallen","artist":"Dar Williams","album":"The Beauty of The Rain","uri":"NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac","trackType":"flac"} Apr 18 23:12:59 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:12:59 volumio volumio[812]: info: ------------------------------ 49ms Apr 18 23:12:59 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:12:59 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:12:59 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mercy of The Fallen","artist":"Dar Williams","album":"The Beauty of The Rain","uri":"NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac","trackType":"flac"} Apr 18 23:12:59 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:12:59 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:59 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:12:59 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:12:59 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:12:59 volumio volumio[812]: info: ------------------------------ 41ms Apr 18 23:13:01 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:13:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:01 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:13:01 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:13:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:13:01 volumio volumio[812]: info: Apr 18 23:13:01 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:01 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:13:01 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:01 volumio volumio[812]: info: sendMpdCommand status took 2 milliseconds Apr 18 23:13:01 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:01 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:13:01 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:01 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:01 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:01 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3359,"duration":252,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2304 Kbps","isStreaming":false,"title":"Mercy of The Fallen","artist":"Dar Williams","album":"The Beauty of The Rain","uri":"NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac","trackType":"flac"} Apr 18 23:13:01 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:01 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:01 volumio volumio[812]: info: ------------------------------ 15ms Apr 18 23:13:03 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:13:03 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:13:03 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:03 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/PCM/Curtis Fuller Apr 18 23:13:03 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/PCM/Curtis Fuller in service mpd Apr 18 23:13:03 volumio volumio[812]: info: Apr 18 23:13:03 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F01%20Five%20Spot%20After%20Dark.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F02%20Undecided.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/02 Undecided.flac Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F03%20Blues-Ette.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/03 Blues-Ette.flac Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F04%20Minor%20Vamp.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/04 Minor Vamp.flac Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F05%20Love%20Your%20Spell%20Is%20Everywhere.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/05 Love Your Spell Is Everywhere.flac Apr 18 23:13:03 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=Curtis%20Fuller/Blues-Ette/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2FCurtis%20Fuller%2FBlues-Ette%2F06%20Twelve-Inch.flac&metadata=false Apr 18 23:13:03 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/Curtis Fuller/Blues-Ette/06 Twelve-Inch.flac Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand stop took 61 milliseconds Apr 18 23:13:03 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand status took 9 milliseconds Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:13:03 volumio volumio[812]: info: Apr 18 23:13:03 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:13:03 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand clear took 4 milliseconds Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac" Apr 18 23:13:03 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: 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":"Mercy of The Fallen","artist":"Dar Williams","album":"The Beauty of The Rain","uri":"NAS/LS510/PCM/Dar Williams/The Beauty of The Rain/01. Mercy Of The Fallen.flac","trackType":"flac"} Apr 18 23:13:03 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:03 volumio volumio[812]: info: No code Apr 18 23:13:03 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:03 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:03 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:03 volumio volumio[812]: info: ------------------------------ 48ms Apr 18 23:13:03 volumio volumio[812]: error: updateQueue error: null Apr 18 23:13:03 volumio volumio[812]: info: Apr 18 23:13:03 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:13:03 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:13:03 volumio volumio[812]: info: ------------------------------ 33ms Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac" took 31 milliseconds Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:13:03 volumio volumio[812]: info: ------------------------------ 10ms Apr 18 23:13:03 volumio volumio[812]: info: sendMpdCommand play took 9 milliseconds Apr 18 23:13:03 volumio volumio[812]: info: Apr 18 23:13:03 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:03 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:03 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:04 volumio volumio[812]: info: Apr 18 23:13:04 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:04 volumio volumio[812]: info: sendMpdCommand status took 40 milliseconds Apr 18 23:13:04 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:04 volumio volumio[812]: info: sendMpdCommand status took 4 milliseconds Apr 18 23:13:04 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:04 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:04 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:04 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Five Spot After Dark","artist":"Curtis Fuller","album":"Blues-Ette","uri":"NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac","trackType":"flac"} Apr 18 23:13:04 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:13:04 volumio volumio[812]: info: ------------------------------ 57ms Apr 18 23:13:04 volumio volumio[812]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 18 23:13:04 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:04 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:04 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:04 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Five Spot After Dark","artist":"Curtis Fuller","album":"Blues-Ette","uri":"NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac","trackType":"flac"} Apr 18 23:13:04 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:13:04 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:04 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:04 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:04 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:04 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:04 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:04 volumio volumio[812]: info: ------------------------------ 51ms Apr 18 23:13:05 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:13:05 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:05 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:13:05 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:13:05 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:13:05 volumio volumio[812]: info: Apr 18 23:13:05 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:05 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:13:05 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:05 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:05 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:13:05 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:05 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:05 volumio volumio[812]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 23:13:05 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:05 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:05 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:05 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:05 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2333,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Five Spot After Dark","artist":"Curtis Fuller","album":"Blues-Ette","uri":"NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac","trackType":"flac"} Apr 18 23:13:05 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:05 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:05 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:05 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:05 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:05 volumio volumio[812]: info: ------------------------------ 23ms Apr 18 23:13:36 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:13:36 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:36 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/PCM/児島未散 Apr 18 23:13:36 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/PCM/児島未散 in service mpd Apr 18 23:13:36 volumio volumio[812]: info: Apr 18 23:13:36 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F01%20BEST%20FRIEND.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F02%20%E3%82%BB%E3%83%97%E3%83%86%E3%83%B3%E3%83%90%E3%83%BC%E7%89%A9%E8%AA%9E%20(%E3%82%B7%E3%83%B3%E3%82%B0%E3%83%AB%E3%83%BB%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%B8%E3%83%A7%E3%83%B3).flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/02 セプテンバー物語 (シングル・ヴァージョン).flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F03%20%E3%83%9E%E3%83%AA%E3%83%B3%E3%83%96%E3%83%AB%E3%83%BC%E3%81%AE%E6%81%8B%E4%BA%BA%E9%81%94.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/03 マリンブルーの恋人達.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F04%20%E3%82%BB%E3%83%94%E3%82%A2My%20true%20love.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/04 セピアMy true love.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F05%20%E4%B8%80%E6%AD%A9%E3%81%9A%E3%81%A4%E3%81%AE%E5%AD%A3%E7%AF%80.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/05 一歩ずつの季節.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F06%20%E8%B5%A4%E3%81%84%E3%83%AA%E3%83%9C%E3%83%B3.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/06 赤いリボン.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F07%20%E6%9C%88%E5%BD%B1%E3%81%AE%E3%82%B5%E3%83%96%E3%83%AA%E3%83%8A.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/07 月影のサブリナ.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F08%20%E3%81%84%E3%81%A4%E3%81%8B%E3%82%89%E6%B3%A3%E3%81%91%E3%81%AA%E3%81%8F%E3%81%AA%E3%81%A3%E3%81%9F%E3%81%A0%E3%82%8D%E3%81%86.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/08 いつから泣けなくなっただろう.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F09%20%E6%82%B2%E3%81%97%E3%81%8F%E3%81%AA%E3%82%93%E3%81%A6.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/09 悲しくなんて.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F10%20%E3%82%B8%E3%83%97%E3%82%B7%E3%83%BC.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/10 ジプシー.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F11%20%E5%90%88%E9%8D%B5.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/11 合鍵.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F12%20%E6%B0%97%E3%81%A5%E3%81%8B%E3%81%AA%E3%81%84%E3%81%A7%E3%81%84%E3%81%A6.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/12 気づかないでいて.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F13%20%E6%98%9F%E5%B1%91%E3%81%AE%E3%82%A8%E3%82%A2%E3%83%9D%E3%83%BC%E3%83%88.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/13 星屑のエアポート.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F14%20HEAVEN%EF%BD%9E%E6%83%85%E7%86%B1%E3%81%AB%E5%98%98%E3%82%92%E3%81%A4%E3%81%84%E3%81%9F%EF%BD%9E.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/14 HEAVEN～情熱に嘘をついた～.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F15%20%E9%95%B7%E3%81%84%E6%89%8B%E7%B4%99.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/15 長い手紙.flac Apr 18 23:13:36 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3/All%20Time%20Best%20Chiffon/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E5%85%90%E5%B3%B6%E6%9C%AA%E6%95%A3%2F16%20%E3%82%B5%E3%83%B3%E3%82%BB%E3%83%83%E3%83%88%E3%83%BB%E3%83%96%E3%83%BC%E3%83%AB%E3%83%90%E3%83%BC%E3%83%89.flac&metadata=false Apr 18 23:13:36 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/児島未散/16 サンセット・ブールバード.flac Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand stop took 170 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::play index 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::play index undefined Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand status took 16 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand stop took 2 milliseconds Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 23:13:36 volumio volumio[812]: info: Apr 18 23:13:36 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:13:36 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand clear took 4 milliseconds Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand add "NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac" Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: 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":"Five Spot After Dark","artist":"Curtis Fuller","album":"Blues-Ette","uri":"NAS/LS510/PCM/Curtis Fuller/Blues-Ette/01 Five Spot After Dark.flac","trackType":"flac"} Apr 18 23:13:36 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: No code Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: ------------------------------ 64ms Apr 18 23:13:36 volumio volumio[812]: error: updateQueue error: null Apr 18 23:13:36 volumio volumio[812]: info: Apr 18 23:13:36 volumio volumio[812]: ---------------------------- MPD announces system playlist update Apr 18 23:13:36 volumio volumio[812]: info: Ignoring MPD Status Update Apr 18 23:13:36 volumio volumio[812]: info: ------------------------------ 42ms Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand add "NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac" took 39 milliseconds Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Apr 18 23:13:36 volumio volumio[812]: info: ------------------------------ 11ms Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand play took 10 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: Apr 18 23:13:36 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:36 volumio volumio[812]: info: Apr 18 23:13:36 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand status took 3 milliseconds Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BEST FRIEND","artist":"児島未散","album":"All Time Best Chiffon","uri":"NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac","trackType":"flac"} Apr 18 23:13:36 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Apr 18 23:13:36 volumio volumio[812]: info: ------------------------------ 20ms Apr 18 23:13:36 volumio volumio[812]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 18 23:13:36 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:36 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BEST FRIEND","artist":"児島未散","album":"All Time Best Chiffon","uri":"NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac","trackType":"flac"} Apr 18 23:13:36 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Apr 18 23:13:36 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:36 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:36 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:36 volumio volumio[812]: info: ------------------------------ 53ms Apr 18 23:13:38 volumio volumio[812]: info: CoreCommandRouter::volumioPause Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::pause Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::servicePause Apr 18 23:13:38 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:38 volumio volumio[812]: info: CoreCommandRouter::servicePause Apr 18 23:13:38 volumio volumio[812]: info: ControllerMpd::pause Apr 18 23:13:38 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Apr 18 23:13:38 volumio volumio[812]: info: sendMpdCommand pause took 2 milliseconds Apr 18 23:13:38 volumio volumio[812]: info: Apr 18 23:13:38 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:38 volumio volumio[812]: info: ControllerMpd::getState Apr 18 23:13:38 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Apr 18 23:13:38 volumio volumio[812]: info: sendMpdCommand status took 1 milliseconds Apr 18 23:13:38 volumio volumio[812]: verbose: ControllerMpd::parseState Apr 18 23:13:38 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 23:13:38 volumio volumio[812]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 23:13:38 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Apr 18 23:13:38 volumio volumio[812]: info: ControllerMpd::pushState Apr 18 23:13:38 volumio volumio[812]: info: CoreCommandRouter::servicePushState Apr 18 23:13:38 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:38 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2837,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1065 Kbps","isStreaming":false,"title":"BEST FRIEND","artist":"児島未散","album":"All Time Best Chiffon","uri":"NAS/LS510/PCM/児島未散/01 BEST FRIEND.flac","trackType":"flac"} Apr 18 23:13:38 volumio volumio[812]: verbose: CURRENT POSITION 0 Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:38 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:38 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:38 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:38 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:38 volumio volumio[812]: info: ------------------------------ 15ms Apr 18 23:13:43 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:43 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::ClearQueue Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::stop Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::pushState Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:43 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 23:13:43 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::serviceStop Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Apr 18 23:13:43 volumio volumio[812]: info: CoreCommandRouter::serviceStop Apr 18 23:13:43 volumio volumio[812]: info: ControllerMpd::stop Apr 18 23:13:43 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::saveQueue Apr 18 23:13:43 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Apr 18 23:13:43 volumio volumio[812]: info: CoreStateMachine::addQueueItems Apr 18 23:13:43 volumio volumio[812]: info: CorePlayQueue::addQueueItems Apr 18 23:13:43 volumio volumio[812]: info: Preload queue cleared Apr 18 23:13:43 volumio volumio[812]: info: Adding Item to queue: music-library/NAS/LS510/PCM/竹内まりや Apr 18 23:13:43 volumio volumio[812]: info: Exploding uri music-library/NAS/LS510/PCM/竹内まりや in service mpd Apr 18 23:13:43 volumio volumio[812]: info: sendMpdCommand stop took 79 milliseconds Apr 18 23:13:43 volumio volumio[812]: info: Apr 18 23:13:43 volumio volumio[812]: ---------------------------- MPD announces state update: player Apr 18 23:13:43 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E7%AB%B9%E5%86%85%E3%81%BE%E3%82%8A%E3%82%84/Bon%20Appetit!/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E7%AB%B9%E5%86%85%E3%81%BE%E3%82%8A%E3%82%84%2FBon%20Appetit.cue&metadata=false Apr 18 23:13:43 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/竹内まりや/Bon Appetit.cue Apr 18 23:13:43 volumio volumio[812]: info: ALBUMART /albumart?cacheid=636&web=%E7%AB%B9%E5%86%85%E3%81%BE%E3%82%8A%E3%82%84/Bon%20Appetit!/extralarge&path=%2Fmnt%2FNAS%2FLS510%2FPCM%2F%E7%AB%B9%E5%86%85%E3%81%BE%E3%82%8A%E3%82%84%2FBon%20Appetit.cue&metadata=false Apr 18 23:13:43 volumio volumio[812]: info: URI /mnt/NAS/LS510/PCM/竹内まりや/Bon Appetit.cue Apr 18 23:13:43 volumio volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 23:13:43 volumio volumio[812]: Error: Unable to resolve or reject the same promise twice Apr 18 23:13:43 volumio volumio[812]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 18 23:13:43 volumio volumio[812]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 18 23:13:43 volumio volumio[812]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 18 23:13:43 volumio volumio[812]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 18 23:13:43 volumio volumio[812]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 18 23:13:43 volumio volumio[812]: at Socket.emit (events.js:315:20) Apr 18 23:13:43 volumio volumio[812]: at addChunk (internal/streams/readable.js:309:12) Apr 18 23:13:43 volumio volumio[812]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 18 23:13:43 volumio volumio[812]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 18 23:13:43 volumio volumio[812]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 18 23:13:43 volumio volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 23:13:43 volumio sudo[3088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 23:12 Apr 18 23:13:43 volumio sudo[3088]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"