Feb 08 09:18:07 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:18:07 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:18:08 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:08 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088 Feb 08 09:18:08 volumio4white volumio[1470]: info: Exploding uri music-library/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088 in service mpd Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F01.%20Toccata%20and%20Fugue%20in%20Dm%2C%20BWV%20565.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F02.%20Concerto%20No.%202%20in%20Am%2C%20BWV%20593%20-%20Allegro.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/02. Concerto No. 2 in Am, BWV 593 - Allegro.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F03.%20Concerto%20No.%202%20in%20Am%2C%20BWV%20593%20-%20Adagio.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/03. Concerto No. 2 in Am, BWV 593 - Adagio.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F04.%20Concerto%20No.%202%20in%20Am%2C%20BWV%20593%20-%20Allegro.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/04. Concerto No. 2 in Am, BWV 593 - Allegro.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F05.%20Prelude%20and%20Fugue%20in%20Bm%2C%20BWV%20544.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/05. Prelude and Fugue in Bm, BWV 544.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FOrgans%20at%20First%20Congregational%20Church%2C%20Los%20Angeles%20CD-80088%2F06.%20Prelude%20and%20Fugue%20in%20D%2C%20BWV%20532.ape&metadata=false Feb 08 09:18:08 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/06. Prelude and Fugue in D, BWV 532.ape Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand stop took 41 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPlay Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::play index 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::play index undefined Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::startPlaybackTimer Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::clearAddPlayTracks NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand stop took 1 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 4 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand clear took 5 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 5 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 5 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 4 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape" Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: No code Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: No code Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: No code Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 44ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 44ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 43ms Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 43ms Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand add "NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape" took 41 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 2ms Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand play Feb 08 09:18:08 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 49ms Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 16ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 15ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 14ms Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand play took 14 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 13ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 14ms Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:08 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 169 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 169 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 168 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 168 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: Feb 08 09:18:08 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 3 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 3 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":273,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 180ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 197ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 197ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 196ms Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":518,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:08 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:08 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:08 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:08 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 57ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 56ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 55ms Feb 08 09:18:08 volumio4white volumio[1470]: info: ------------------------------ 54ms Feb 08 09:18:17 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:18:17 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:17 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲) Feb 08 09:18:17 volumio4white volumio[1470]: info: Exploding uri music-library/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲) in service mpd Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Williams%2C%20John/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F01.%20Olympic%20Fanfare.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Suk%2C%20Josef/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F02.%20Towards%20a%20New%20Life%2C%20Op.35c.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/02. Towards a New Life, Op.35c.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Elgar%2C%20Sir%20Edward/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F03.%20Pomp%20and%20Circumstance%20March%20No.%201.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/03. Pomp and Circumstance March No. 1.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Ireland%2C%20John/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F04.%20Epic%20March.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/04. Epic March.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Tchaikovsky%2C%20Peter%20Ilyich/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F05.%20Coronation%20March%20for%20Czar%20Alexander%20III.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/05. Coronation March for Czar Alexander III.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Berlioz%2C%20Hector/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F06.%20Rakoczy%20March%20from%20The%20Damnation%20of%20Faust%2C%20Op.%2024.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/06. Rakoczy March from The Damnation of Faust, Op. 24.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Wagner%2C%20Josef%20Franz/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F07.%20Under%20the%20Double%20Eagle.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/07. Under the Double Eagle.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Fucik%2C%20Julius/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F08.%20Entry%20of%20the%20Gladiators.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/08. Entry of the Gladiators.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Sousa%2C%20John%20Phillip/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F09.%20The%20Stars%20and%20Stripes%20Forever.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/09. The Stars and Stripes Forever.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Hayman%2C%20Richard%20(arr.)/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F10.%20March%20Medley-%20Strike%20Up%20the%20Band%2C%2076%20Trombones%2C%20South%20Rampart%20Street%20Parad.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/10. March Medley- Strike Up the Band, 76 Trombones, South Rampart Street Parad.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Hayman%2C%20Richard%20(arr.)/Pomp%20%26%20Pizazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FPomp%20%26%20Pizaz(%E5%8D%8E%E4%B8%BD%E5%A8%81%E7%8C%9B%E8%BF%9B%E8%A1%8C%E6%9B%B2)%2F11.%20When%20the%20Saints%20Go%20Marching%20In.ape&metadata=false Feb 08 09:18:17 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/11. When the Saints Go Marching In.ape Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand stop took 42 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPlay Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::play index 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::play index undefined Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::startPlaybackTimer Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::clearAddPlayTracks NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 6 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand stop took 1 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand clear took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 3 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 3 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape" Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: 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":"01. Toccata and Fugue in Dm, BWV 565.ape","artist":null,"album":null,"uri":"NAS/rtnas/04TELARC/Organs at First Congregational Church, Los Angeles CD-80088/01. Toccata and Fugue in Dm, BWV 565.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: No code Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: No code Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: No code Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 44ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 39ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 39ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 39ms Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 39ms Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand add "NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape" took 38 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 2ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand play Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:17 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 19ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 18ms Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand play took 18 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 18ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 18ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 17ms Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 188 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 189 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 189 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 188 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: Feb 08 09:18:17 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 199ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 213ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 213ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 213ms Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:17 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:17 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:17 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:17 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 59ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 58ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 58ms Feb 08 09:18:17 volumio4white volumio[1470]: info: ------------------------------ 58ms Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:18:22 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:22 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 Feb 08 09:18:22 volumio4white volumio[1470]: info: Exploding uri music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 in service mpd Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand stop took 54 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Al%20Di%20Meola/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F01.%20Zona%20Desperata.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Ray%20Brown%2C%20Monty%20Alexander%2C%20Russell%20Malone/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F02.%20Django.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/02. Django.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Gerry%20Mulligan/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F03.%20Oh%20Mr.%20Sauter%20%20Yes%20Mr.%20Finegan.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/03. Oh Mr. Sauter Yes Mr. Finegan.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Ray%20Brown%2C%20John%20Clayton%2C%20Christian%20McBride/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F04.%20Papa%20Was%20A%20Rolling%20Stone.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/04. Papa Was A Rolling Stone.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Tierney%20Sutton/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F05.%20Alone%20Together.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/05. Alone Together.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=McCoy%20Tyner%2C%20Bobby%20Hutcherson%2C%20Charnett%20Moffett%2C%20Eric%20Harland/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F06.%20If%20I%20Were%20A%20Bell.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/06. If I Were A Bell.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Michel%20Camilo/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F07.%20Tequila.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/07. Tequila.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=John%20Pizzarelli%20Trio/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F08.%20I%20Like%20Jersey%20Best.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/08. I Like Jersey Best.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Jacques%20Loussier%20Trio/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F09.%20Air%20On%20A%20G%20String.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/09. Air On A G String.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Hiromi/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F10.%20Another%20Mind.ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/10. Another Mind.ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Monty%20Alexander/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2F11.%20Battle%20Hymn%20of%20the%20Republic%20(Glory%20Hallelujah).ape&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/11. Battle Hymn of the Republic (Glory Hallelujah).ape Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Al%20Di%20Meola/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F01%20-%20Al%20Di%20Meola%20-%20Zona%20Desperata.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/01 - Al Di Meola - Zona Desperata.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Ray%20Brown%2C%20Monty%20Alexander%2C%20Russell%20Malone/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F02%20-%20Ray%20Brown%2C%20Monty%20Alexander%2C%20Russell%20Malone%20-%20Django.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/02 - Ray Brown, Monty Alexander, Russell Malone - Django.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Gerry%20Mulligan/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F03%20-%20Gerry%20Mulligan%20-%20Oh%20Mr.%20Sauter%20Yes%20Mr.%20Finegan.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/03 - Gerry Mulligan - Oh Mr. Sauter Yes Mr. Finegan.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Ray%20Brown%2C%20John%20Clayton%2C%20Christian%20McBride/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F04%20-%20Ray%20Brown%2C%20John%20Clayton%2C%20Christian%20McBride%20-%20Papa%20Was%20A%20Rolling%20Stone.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/04 - Ray Brown, John Clayton, Christian McBride - Papa Was A Rolling Stone.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Tierney%20Sutton/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F05%20-%20Tierney%20Sutton%20-%20Alone%20Together.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/05 - Tierney Sutton - Alone Together.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=McCoy%20Tyner%2C%20Bobby%20Hutcherson%2C%20Charnett%20Moffett%2C%20Eric%20Harland/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F06%20-%20McCoy%20Tyner%2C%20Bobby%20Hutcherson%2C%20Charnett%20Moffett%2C%20Eric%20Harla%20-%20If%20I%20Were%20A%20Bell.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/06 - McCoy Tyner, Bobby Hutcherson, Charnett Moffett, Eric Harla - If I Were A Bell.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Michel%20Camilo/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F07%20-%20Michel%20Camilo%20-%20Tequila.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/07 - Michel Camilo - Tequila.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=John%20Pizzarelli%20Trio/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F08%20-%20John%20Pizzarelli%20Trio%20-%20I%20Like%20Jersey%20Best.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/08 - John Pizzarelli Trio - I Like Jersey Best.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Jacques%20Loussier%20Trio/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F09%20-%20Jacques%20Loussier%20Trio%20-%20Air%20On%20A%20G%20String.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/09 - Jacques Loussier Trio - Air On A G String.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Hiromi/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F10%20-%20Hiromi%20-%20Another%20Mind.dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/10 - Hiromi - Another Mind.dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Monty%20Alexander/SACD%20Sampler%203%3A%20Jazz/large&path=%2Fmnt%2FNAS%2Frtnas%2F04TELARC%2FJazz%20SACD%20SAMPLE%203%2FDSF%2F11%20-%20Monty%20Alexander%20-%20Battle%20Hymn%20of%20the%20Republic%20(Glory%20Hallelujah).dsf&metadata=false Feb 08 09:18:22 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/DSF/11 - Monty Alexander - Battle Hymn of the Republic (Glory Hallelujah).dsf Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand status took 6 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand status took 6 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand status took 7 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand status took 6 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPlay Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::play index 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::play index undefined Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::startPlaybackTimer Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::clearAddPlayTracks NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand stop took 0 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: 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":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:22 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: No code Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: 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":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:22 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: No code Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: 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":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:22 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: No code Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: 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":"Olympic Fanfare","artist":"Williams, John","album":"Pomp & Pizazz","uri":"NAS/rtnas/04TELARC/Pomp & Pizaz(华丽威猛进行曲)/01. Olympic Fanfare.ape","trackType":"ape"} Feb 08 09:18:22 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: No code Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 61ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 61ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 61ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 62ms Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand clear took 62 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 6ms Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" took 6 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 6ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 6ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 5ms Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand play Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:22 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 101ms Feb 08 09:18:22 volumio4white volumio[1470]: info: sendMpdCommand play took 99 milliseconds Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 99ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 100ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ------------------------------ 99ms Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:22 volumio4white volumio[1470]: info: Feb 08 09:18:22 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:22 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:22 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:23 volumio4white volumio[1470]: info: Feb 08 09:18:23 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 189 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 190 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 189 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 189 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 192ms Feb 08 09:18:23 volumio4white volumio[1470]: info: Feb 08 09:18:23 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:23 volumio4white volumio[1470]: info: Feb 08 09:18:23 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:23 volumio4white volumio[1470]: info: Feb 08 09:18:23 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":216,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":307,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":558,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 227ms Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 226ms Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 226ms Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 40ms Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand status took 36 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 59ms Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 59ms Feb 08 09:18:23 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 08 09:18:23 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:23 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:23 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:23 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:23 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:23 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:23 volumio4white volumio[1470]: info: ------------------------------ 78ms Feb 08 09:18:25 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:18:25 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:25 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 Feb 08 09:18:25 volumio4white volumio[1470]: info: Using cached record of: music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPlay Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::play index 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::play index undefined Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::startPlaybackTimer Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::clearAddPlayTracks NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand stop took 27 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand stop took 20 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand clear took 1 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand status took 2 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:25 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:25 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:25 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:25 volumio4white volumio[1470]: info: No code Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:25 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:25 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 10ms Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 9ms Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 15ms Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 16ms Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" took 15 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 2ms Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 2ms Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand play Feb 08 09:18:25 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 19ms Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:25 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 7ms Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 6ms Feb 08 09:18:25 volumio4white volumio[1470]: info: sendMpdCommand play took 4 milliseconds Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 4ms Feb 08 09:18:25 volumio4white volumio[1470]: info: ------------------------------ 4ms Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:25 volumio4white volumio[1470]: info: Feb 08 09:18:25 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:25 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:25 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:26 volumio4white volumio[1470]: info: Feb 08 09:18:26 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 242 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: info: Feb 08 09:18:26 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 243 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 243 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 242 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 246ms Feb 08 09:18:26 volumio4white volumio[1470]: info: Feb 08 09:18:26 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:26 volumio4white volumio[1470]: info: Feb 08 09:18:26 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":353,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":353,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":490,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 275ms Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 274ms Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 273ms Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 33ms Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 32ms Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand status took 30 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 38ms Feb 08 09:18:26 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 08 09:18:26 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:26 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:26 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:26 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:26 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:26 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:26 volumio4white volumio[1470]: info: ------------------------------ 58ms Feb 08 09:18:28 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:18:28 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:18:28 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 Feb 08 09:18:28 volumio4white volumio[1470]: info: Using cached record of: music-library/NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPlay Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::play index 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::play index undefined Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::startPlaybackTimer Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::clearAddPlayTracks NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand stop took 20 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand stop took 12 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand clear took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 0 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: No code Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: No code Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: No code Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 25ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 25ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 26ms Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: error: updateQueue error: null Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 26ms Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand add "NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape" took 26 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 1ms Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand play Feb 08 09:18:28 volumio4white volumio[1470]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 28ms Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces system playlist update Feb 08 09:18:28 volumio4white volumio[1470]: info: Ignoring MPD Status Update Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 10ms Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand play took 10 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 9ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 9ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 9ms Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 236 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 237 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 237 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 237 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus stop Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 238ms Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: Feb 08 09:18:28 volumio4white volumio[1470]: ---------------------------- MPD announces state update: player Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::getState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand status Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 0 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":558,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":661,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 260ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 260ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 260ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 24ms Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand status took 23 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseState Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 36ms Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 35ms Feb 08 09:18:28 volumio4white volumio[1470]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 08 09:18:28 volumio4white volumio[1470]: verbose: ControllerMpd::parseTrackInfo Feb 08 09:18:28 volumio4white volumio[1470]: info: ControllerMpd::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::servicePushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":729,"duration":567,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zona Desperata","artist":"Al Di Meola","album":"SACD Sampler 3: Jazz","uri":"NAS/rtnas/04TELARC/Jazz SACD SAMPLE 3/01. Zona Desperata.ape","trackType":"ape"} Feb 08 09:18:28 volumio4white volumio[1470]: verbose: CURRENT POSITION 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState stateService play Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::syncState currentStatus play Feb 08 09:18:28 volumio4white volumio[1470]: info: Received an update from plugin. extracting info from payload Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:18:28 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:28 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:18:28 volumio4white volumio[1470]: info: ------------------------------ 52ms Feb 08 09:18:37 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:18:37 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:18:52 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:18:52 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:07 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:19:07 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:22 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:19:22 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:37 volumio4white volumio[1470]: info: CoreCommandRouter::volumioGetState Feb 08 09:19:37 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:48 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::ClearQueue Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::stop Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::stPlaybackTimer Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::updateTrackBlock Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::getTrackBlock Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::pushState Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushState Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::serviceStop Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::getTrack 0 Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreCommandRouter::serviceStop Feb 08 09:19:48 volumio4white volumio[1470]: info: ControllerMpd::stop Feb 08 09:19:48 volumio4white volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::clearPlayQueue Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::saveQueue Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreCommandRouter::volumioPushQueue Feb 08 09:19:48 volumio4white volumio[1470]: info: CoreStateMachine::addQueueItems Feb 08 09:19:48 volumio4white volumio[1470]: info: CorePlayQueue::addQueueItems Feb 08 09:19:48 volumio4white volumio[1470]: info: Preload queue cleared Feb 08 09:19:48 volumio4white volumio[1470]: info: Adding Item to queue: music-library/NAS/rtnas/11TACET 发烧合集 Feb 08 09:19:48 volumio4white volumio[1470]: info: Exploding uri music-library/NAS/rtnas/11TACET 发烧合集 in service mpd Feb 08 09:19:49 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=//large&path=%2Fmnt%2FNAS%2Frtnas%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.ape&metadata=false Feb 08 09:19:49 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.ape Feb 08 09:19:49 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Wolfgang%20Amadeus%20Mozart/Tre%20Sonate/large&path=%2Fmnt%2FNAS%2Frtnas%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.cue&metadata=false Feb 08 09:19:49 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.cue Feb 08 09:19:49 volumio4white volumio[1470]: info: ALBUMART /albumart?cacheid=218&web=Wolfgang%20Amadeus%20Mozart/Tre%20Sonate/large&path=%2Fmnt%2FNAS%2Frtnas%2F11TACET%20%E5%8F%91%E7%83%A7%E5%90%88%E9%9B%86%2FTACET-006%20Mozart%20-%20Tre%20Sonate%20-gerrit%20zitterbart(piano)%2FWolfgang%20Amadeus%20Mozart%20-%20Tre%20Sonate.cue&metadata=false Feb 08 09:19:49 volumio4white volumio[1470]: info: URI /mnt/NAS/rtnas/11TACET 发烧合集/TACET-006 Mozart - Tre Sonate -gerrit zitterbart(piano)/Wolfgang Amadeus Mozart - Tre Sonate.cue Feb 08 09:19:49 volumio4white volumio[1470]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 09:19:49 volumio4white volumio[1470]: Error: Unable to resolve or reject the same promise twice Feb 08 09:19:49 volumio4white volumio[1470]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 08 09:19:49 volumio4white volumio[1470]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Feb 08 09:19:49 volumio4white volumio[1470]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 08 09:19:49 volumio4white volumio[1470]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 08 09:19:49 volumio4white volumio[1470]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 08 09:19:49 volumio4white volumio[1470]: at Socket.emit (node:events:514:28) Feb 08 09:19:49 volumio4white volumio[1470]: at addChunk (node:internal/streams/readable:343:12) Feb 08 09:19:49 volumio4white volumio[1470]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 08 09:19:49 volumio4white volumio[1470]: at Readable.push (node:internal/streams/readable:253:10) Feb 08 09:19:49 volumio4white volumio[1470]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 08 09:19:49 volumio4white volumio[1470]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 09:19:49 volumio4white sudo[5671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 09:18' Feb 08 09:19:49 volumio4white sudo[5671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"