-- Logs begin at Sun 2025-03-16 12:09:20 UTC, end at Sun 2025-03-16 12:25:20 UTC. -- Mar 16 12:24:12 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:12 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2 Mar 16 12:24:12 volumio volumio[2276]: info: Exploding uri music-library/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2 in service mpd Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2F1994-%E5%BC%A0%E5%AD%A6%E5%8F%8B24KGold%E9%87%91%E8%97%8F%E9%9B%86%5BPolyGram%5D%2FDisc2%2FCDImage.APE&metadata=false Mar 16 12:24:12 volumio volumio[2276]: info: URI /mnt/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand stop took 5 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 4 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand stop took 1 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand clear took 0 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE" Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: 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":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: No code Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:12 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 16ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 12ms Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 12ms Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE" took 10 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 2ms Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand play took 2 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 2ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: Mar 16 12:24:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 11ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:24:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3627,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc2/CDImage.APE","trackType":"APE"} Mar 16 12:24:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 30ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:12 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:14 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:14 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:14 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1 Mar 16 12:24:14 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand stop took 6 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand stop took 1 milliseconds Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand clear took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: 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} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: No code Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: 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} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: No code Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" took 13 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:24:14 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 16ms Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:14 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand play took 3 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: Mar 16 12:24:14 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:14 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:14 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 11ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 15ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 15ms Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 16 12:24:14 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:14 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:14 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:14 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:14 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:14 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:14 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:14 volumio volumio[2276]: info: ------------------------------ 25ms Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","service":"mpd","name":"CDImage.APE","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2F1994-%E5%BC%A0%E5%AD%A6%E5%8F%8B24KGold%E9%87%91%E8%97%8F%E9%9B%86%5BPolyGram%5D%2FDisc1&metadata=false","duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"APE","channels":2} Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: error: null Mar 16 12:24:17 volumio volumio[2276]: info: Mar 16 12:24:17 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:17 volumio volumio[2276]: info: Mar 16 12:24:17 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:17 volumio volumio[2276]: info: Mar 16 12:24:17 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:17 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:17 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:17 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:17 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:17 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:17 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:17 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:17 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:17 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:17 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:17 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:17 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:17 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:17 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:17 volumio volumio[2276]: info: ------------------------------ 20ms Mar 16 12:24:17 volumio volumio[2276]: info: ------------------------------ 19ms Mar 16 12:24:17 volumio volumio[2276]: info: ------------------------------ 19ms Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","service":"mpd","name":"CDImage.APE","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2F1994-%E5%BC%A0%E5%AD%A6%E5%8F%8B24KGold%E9%87%91%E8%97%8F%E9%9B%86%5BPolyGram%5D%2FDisc1&metadata=false","duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"APE","channels":2} Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: error: null Mar 16 12:24:20 volumio volumio[2276]: info: Mar 16 12:24:20 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:20 volumio volumio[2276]: info: Mar 16 12:24:20 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:20 volumio volumio[2276]: info: Mar 16 12:24:20 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:20 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:20 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:20 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:20 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:20 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:20 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:20 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:20 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:20 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:20 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:20 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:20 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:20 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","service":"mpd","name":"CDImage.APE","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2F1994-%E5%BC%A0%E5%AD%A6%E5%8F%8B24KGold%E9%87%91%E8%97%8F%E9%9B%86%5BPolyGram%5D%2FDisc1&metadata=false","duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"APE","channels":2} Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: error: null Mar 16 12:24:23 volumio volumio[2276]: info: Mar 16 12:24:23 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:23 volumio volumio[2276]: info: Mar 16 12:24:23 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:23 volumio volumio[2276]: info: Mar 16 12:24:23 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:23 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:23 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1101000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:23 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:23 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1101000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:23 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:23 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1101000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:23 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:23 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:23 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:23 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:23 volumio volumio[2276]: info: ------------------------------ 32ms Mar 16 12:24:23 volumio volumio[2276]: info: ------------------------------ 32ms Mar 16 12:24:23 volumio volumio[2276]: info: ------------------------------ 32ms Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","service":"mpd","name":"CDImage.APE","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2F1994-%E5%BC%A0%E5%AD%A6%E5%8F%8B24KGold%E9%87%91%E8%97%8F%E9%9B%86%5BPolyGram%5D%2FDisc1&metadata=false","duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"APE","channels":2} Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: error: null Mar 16 12:24:24 volumio volumio[2276]: info: Mar 16 12:24:24 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:24 volumio volumio[2276]: info: Mar 16 12:24:24 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:24 volumio volumio[2276]: info: Mar 16 12:24:24 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:24 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:24 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1846000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:24 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:24 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1846000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:24 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:24 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1846000,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:24 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:24 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:24 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:24 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:24 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:24 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:24 volumio volumio[2276]: info: ------------------------------ 29ms Mar 16 12:24:34 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:34 volumio volumio[2276]: info: CURURI: music-library Mar 16 12:24:34 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:35 volumio volumio[2276]: info: CURURI: music-library/USB Mar 16 12:24:35 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:35 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:35 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:35 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1 Mar 16 12:24:35 volumio volumio[2276]: info: Using cached record of: music-library/USB/1 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand stop took 32 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand stop took 6 milliseconds Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand clear took 1 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: 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} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: No code Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: 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} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: No code Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 18ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 18ms Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 21ms Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" took 20 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:24:35 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 24ms Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:35 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 6ms Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand play took 4 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 5ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: Mar 16 12:24:35 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:35 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:35 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 9ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 16 12:24:35 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:35 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:35 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:35 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:35 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:35 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:35 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:35 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:37 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:37 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:37 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1 Mar 16 12:24:37 volumio volumio[2276]: info: Using cached record of: music-library/USB/1 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand stop took 39 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand stop took 7 milliseconds Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand clear took 2 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: 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} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: No code Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: 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} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: No code Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 22ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 22ms Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand add "USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE" took 27 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 5ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 5ms Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:24:37 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 30ms Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:37 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 9ms Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand play took 7 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 7ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 7ms Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: Mar 16 12:24:37 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:37 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:37 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 8ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 16 12:24:37 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:37 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:37 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":3066,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.APE","artist":null,"album":null,"uri":"USB/1/1994-张学友24KGold金藏集[PolyGram]/Disc1/CDImage.APE","trackType":"APE"} Mar 16 12:24:37 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:37 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:37 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:37 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 25ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 24ms Mar 16 12:24:37 volumio volumio[2276]: info: ------------------------------ 24ms Mar 16 12:24:38 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:38 volumio volumio[2276]: info: CURURI: music-library/USB/1 Mar 16 12:24:38 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:49 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:49 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res Mar 16 12:24:49 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:49 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/01 Hotel California.flac Mar 16 12:24:50 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/01 Hotel California.flac in service mpd Mar 16 12:24:50 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=The%20Eagles/Hotel%20California/extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2F01%20Hotel%20California.flac&metadata=false Mar 16 12:24:50 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/01 Hotel California.flac Mar 16 12:24:52 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:52 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Piano Mar 16 12:24:52 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:53 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:24:53 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF] Mar 16 12:24:53 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:24:53 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F01.Love%20at%20First%20Sight.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F02.%20Letter%20to%20my%20Mother.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F03.%20The%20Ocean.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F04.%20Wedding%20of%20Love.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F05.%20Sagittarius.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F06.%20Diva.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F07.%20So%20Sad.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:24:53 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff in service mpd Mar 16 12:24:53 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F08.%20Secret%20Garden.dff&metadata=false Mar 16 12:24:53 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F09.%20Journey%20to%20Venice.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F10.%20A%20Dream%20Story.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F11.%20Pastoral.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F12.%20Rondo%20for%20a%20Little%20Child.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F13.%20Doves%20from%20the%20Tenere.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F14.%20Regrets.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F15.%20Childhood%20Memories.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:24:54 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff in service mpd Mar 16 12:24:54 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D%2F16.%20Time%20Passing.dff&metadata=false Mar 16 12:24:54 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:24:54 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:54 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:24:54 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:24:54 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:24:54 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand stop took 18 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand stop took 2 milliseconds Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand clear took 2 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff" Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: 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} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: No code Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: 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} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: No code Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 28ms Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand add "USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff" took 27 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 2ms Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:24:54 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 32ms Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:24:54 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 7ms Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand play took 6 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 12 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 13 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 12 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:24:54 volumio volumio[2276]: info: ------------------------------ 15ms Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: Mar 16 12:24:54 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 12:24:54 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:54 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":261,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:54 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":261,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:54 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":313,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:54 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:54 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:54 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:54 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:54 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:55 volumio volumio[2276]: info: ------------------------------ 31ms Mar 16 12:24:55 volumio volumio[2276]: info: ------------------------------ 30ms Mar 16 12:24:55 volumio volumio[2276]: info: ------------------------------ 19ms Mar 16 12:24:55 volumio volumio[2276]: info: sendMpdCommand status took 17 milliseconds Mar 16 12:24:55 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 16 12:24:55 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:55 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:55 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:55 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:55 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:55 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:55 volumio volumio[2276]: info: ------------------------------ 22ms Mar 16 12:24:55 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 16 12:24:55 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:55 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":552,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:55 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:55 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:55 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:55 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:55 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:55 volumio volumio[2276]: info: ------------------------------ 30ms Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","service":"mpd","name":"01.Love at First Sight.dff","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D&metadata=false","duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","trackType":"dff","channels":2} Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: error: null Mar 16 12:24:58 volumio volumio[2276]: info: Mar 16 12:24:58 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:58 volumio volumio[2276]: info: Mar 16 12:24:58 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:58 volumio volumio[2276]: info: Mar 16 12:24:58 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:58 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:58 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:58 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:58 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: ------------------------------ 11ms Mar 16 12:24:58 volumio volumio[2276]: info: ------------------------------ 10ms Mar 16 12:24:58 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 16 12:24:58 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:58 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:58 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:58 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:58 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:58 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:58 volumio volumio[2276]: info: ------------------------------ 15ms Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioSeek Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::seek Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: TRACKBLOCK {"uri":"mnt/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","service":"mpd","name":"01.Love at First Sight.dff","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=//extralarge&path=%2FUSB%2F1%2FHi-res%2FPiano%2FRichard%20Clayderman%20-%20Souvenirs%20(1989)%20%5BSACD-DFF%5D&metadata=false","duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","trackType":"dff","channels":2} Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::seek Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: error: null Mar 16 12:24:59 volumio volumio[2276]: info: Mar 16 12:24:59 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:59 volumio volumio[2276]: info: Mar 16 12:24:59 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:59 volumio volumio[2276]: info: Mar 16 12:24:59 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:59 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:59 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:59 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:59 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:59 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:59 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:24:59 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:24:59 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:24:59 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:24:59 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:24:59 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:24:59 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:25:10 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:25:10 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Piano Mar 16 12:25:10 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:11 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:25:11 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF] Mar 16 12:25:11 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:12 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:25:12 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:25:12 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/02. Letter to my Mother.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/03. The Ocean.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/04. Wedding of Love.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/05. Sagittarius.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/06. Diva.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/07. So Sad.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/08. Secret Garden.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/09. Journey to Venice.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/10. A Dream Story.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/11. Pastoral.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/12. Rondo for a Little Child.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/13. Doves from the Tenere.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/14. Regrets.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/15. Childhood Memories.dff Mar 16 12:25:12 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:25:12 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/16. Time Passing.dff Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand stop took 10 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand stop took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand clear took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff" Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: 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} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: No code Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: 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} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: No code Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 18ms Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand add "USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff" took 17 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:25:12 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 20ms Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:12 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand play took 3 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 3ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 2ms Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 2 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: Mar 16 12:25:12 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 12ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 12ms Mar 16 12:25:12 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 16 12:25:12 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96,"duration":256,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01.Love at First Sight.dff","artist":null,"album":null,"uri":"USB/1/Hi-res/Piano/Richard Clayderman - Souvenirs (1989) [SACD-DFF]/01.Love at First Sight.dff","trackType":"dff"} Mar 16 12:25:12 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:12 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:12 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:12 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 27ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 25ms Mar 16 12:25:12 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:25:17 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:25:17 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Test Audio Mar 16 12:25:17 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:18 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 12:25:18 volumio volumio[2276]: info: CURURI: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004) Mar 16 12:25:18 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:18 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav Mar 16 12:25:18 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/10. Long After You're Gone.wav Mar 16 12:25:18 volumio volumio[2276]: info: Preloading song: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/12. The Puppet Opera.wav Mar 16 12:25:18 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav in service mpd Mar 16 12:25:18 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FTest%20Audio%2FB%26W%20presents%20Very%20Audiophile%20New%20Recordings%20(2004)%2F04.%20Flesh%20and%20Blood.wav&metadata=false Mar 16 12:25:18 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav Mar 16 12:25:18 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/10. Long After You're Gone.wav in service mpd Mar 16 12:25:18 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FTest%20Audio%2FB%26W%20presents%20Very%20Audiophile%20New%20Recordings%20(2004)%2F10.%20Long%20After%20You're%20Gone.wav&metadata=false Mar 16 12:25:18 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/10. Long After You're Gone.wav Mar 16 12:25:18 volumio volumio[2276]: info: Exploding uri music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/12. The Puppet Opera.wav in service mpd Mar 16 12:25:18 volumio volumio[2276]: info: ALBUMART /albumart?cacheid=29&web=//extralarge&path=%2Fmnt%2FUSB%2F1%2FHi-res%2FTest%20Audio%2FB%26W%20presents%20Very%20Audiophile%20New%20Recordings%20(2004)%2F12.%20The%20Puppet%20Opera.wav&metadata=false Mar 16 12:25:18 volumio volumio[2276]: info: URI /mnt/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/12. The Puppet Opera.wav Mar 16 12:25:19 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::ClearQueue Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::stPlaybackTimer Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::serviceStop Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::serviceStop Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::stop Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::clearPlayQueue Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:25:19 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:19 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav Mar 16 12:25:19 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPlay Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::play index 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::addQueueItems Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::addQueueItems Mar 16 12:25:19 volumio volumio[2276]: info: Preload queue cleared Mar 16 12:25:19 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/10. Long After You're Gone.wav Mar 16 12:25:19 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/10. Long After You're Gone.wav Mar 16 12:25:19 volumio volumio[2276]: info: Adding Item to queue: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/12. The Puppet Opera.wav Mar 16 12:25:19 volumio volumio[2276]: info: Using cached record of: music-library/USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/12. The Puppet Opera.wav Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushQueue Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::saveQueue Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::play index undefined Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::updateTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrackBlock Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::startPlaybackTimer Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::clearAddPlayTracks USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand stop took 8 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand stop took 1 milliseconds Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand clear took 1 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand add "USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav" Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: 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} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: No code Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: 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} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: No code Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 13ms Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: error: updateQueue error: null Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 15ms Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand add "USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav" took 14 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 1ms Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand play Mar 16 12:25:19 volumio volumio[2276]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 18ms Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces system playlist update Mar 16 12:25:19 volumio volumio[2276]: info: Ignoring MPD Status Update Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 7ms Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand play took 4 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 4ms Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 9 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 10 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 9 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:19 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 14ms Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 16ms Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: Mar 16 12:25:19 volumio volumio[2276]: ---------------------------- MPD announces state update: player Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::getState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 1 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand status took 0 milliseconds Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseState Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:19 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:19 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 26ms Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 17ms Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 16 12:25:19 volumio volumio[2276]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: verbose: ControllerMpd::parseTrackInfo Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:19 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ControllerMpd::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::servicePushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04. Flesh and Blood.wav","artist":null,"album":null,"uri":"USB/1/Hi-res/Test Audio/B&W presents Very Audiophile New Recordings (2004)/04. Flesh and Blood.wav","trackType":"wav"} Mar 16 12:25:19 volumio volumio[2276]: verbose: CURRENT POSITION 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState stateService play Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::syncState currentStatus play Mar 16 12:25:19 volumio volumio[2276]: info: Received an update from plugin. extracting info from payload Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: CoreStateMachine::pushState Mar 16 12:25:19 volumio volumio[2276]: info: CorePlayQueue::getTrack 0 Mar 16 12:25:19 volumio volumio[2276]: info: CoreCommandRouter::volumioPushState Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 25ms Mar 16 12:25:19 volumio volumio[2276]: info: ------------------------------ 25ms Mar 16 12:25:19 volumio volumio[2276]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:25:19 volumio volumio[2276]: Error: connect ETIMEDOUT 128.121.146.101:443 Mar 16 12:25:19 volumio volumio[2276]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 16 12:25:19 volumio volumio[2276]: errno: -110, Mar 16 12:25:19 volumio volumio[2276]: code: 'ETIMEDOUT', Mar 16 12:25:19 volumio volumio[2276]: syscall: 'connect', Mar 16 12:25:19 volumio volumio[2276]: address: '128.121.146.101', Mar 16 12:25:19 volumio volumio[2276]: port: 443 Mar 16 12:25:19 volumio volumio[2276]: } Mar 16 12:25:19 volumio volumio[2276]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:25:20 volumio sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 12:24 Mar 16 12:25:20 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"