-- Logs begin at Tue 2025-12-30 12:09:08 UTC, end at Tue 2025-12-30 12:23:38 UTC. -- Dec 30 12:22:32 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 30 12:22:32 volumio volumio[838]: info: CURURI: music-library/USB/B8A4218AA4214C66 Dec 30 12:22:33 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:35 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 30 12:22:35 volumio volumio[838]: info: CURURI: music-library/USB/B8A4218AA4214C66/Program Files Dec 30 12:22:35 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:38 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::serviceStop Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::serviceStop Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::stop Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:22:38 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:38 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/Program Files/Tencent Dec 30 12:22:38 volumio volumio[838]: info: Exploding uri music-library/USB/B8A4218AA4214C66/Program Files/Tencent in service mpd Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand stop took 488 milliseconds Dec 30 12:22:38 volumio volumio[838]: info: Dec 30 12:22:38 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2FAudio.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2FGlobal.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Global.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2Fmsg.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/msg.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2Fshake.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/shake.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2Fsystem.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/system.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FMisc%2FSound%2FClassic%2Ftweet.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/tweet.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FPlugin%2FCom.Tencent.AudioVideo%2FMisc%2FGAudio_Call.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Plugin/Com.Tencent.AudioVideo/Misc/GAudio_Call.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FPlugin%2FCom.Tencent.AudioVideo%2FMisc%2FGAudio_Receive.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Plugin/Com.Tencent.AudioVideo/Misc/GAudio_Receive.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FPlugin%2FCom.Tencent.AudioVideo%2FMisc%2FSoundTest.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Plugin/Com.Tencent.AudioVideo/Misc/SoundTest.wav Dec 30 12:22:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FTencent%2FQQLite%2FPlugin%2FCom.Tencent.AudioVideo%2FMisc%2FVideoShow_Countdown.wav&metadata=false Dec 30 12:22:38 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Plugin/Com.Tencent.AudioVideo/Misc/VideoShow_Countdown.wav Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand status took 79 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand stop took 1 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 如果你是我的传说.wav","artist":null,"album":null,"uri":"USB/5CD027E6D027C55C/刘德华/01 如果你是我的传说.wav","trackType":"wav"} Dec 30 12:22:38 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:38 volumio volumio[838]: info: No code Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:38 volumio volumio[838]: info: ------------------------------ 172ms Dec 30 12:22:38 volumio volumio[838]: info: Dec 30 12:22:38 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:38 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand clear took 48 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav" Dec 30 12:22:38 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:38 volumio volumio[838]: info: Dec 30 12:22:38 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:38 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:38 volumio volumio[838]: info: ------------------------------ 4ms Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav" took 3 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:22:38 volumio volumio[838]: info: Dec 30 12:22:38 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:38 volumio volumio[838]: info: ------------------------------ 11ms Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand play took 10 milliseconds Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:38 volumio volumio[838]: info: Dec 30 12:22:38 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand status took 6 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand status took 3 milliseconds Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"2.705 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Audio.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav","trackType":"wav"} Dec 30 12:22:38 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:22:38 volumio volumio[838]: info: ------------------------------ 20ms Dec 30 12:22:38 volumio volumio[838]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 30 12:22:38 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:38 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"2.705 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Audio.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Audio.wav","trackType":"wav"} Dec 30 12:22:38 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:38 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:38 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:38 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:39 volumio volumio[838]: info: ------------------------------ 90ms Dec 30 12:22:39 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:39 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:39 volumio volumio[838]: info: Prefetching next song Dec 30 12:22:39 volumio volumio[838]: info: DOING PREFETCH IN MPD Dec 30 12:22:39 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Global.wav" Dec 30 12:22:39 volumio volumio[838]: info: Dec 30 12:22:39 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:39 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:39 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/Global.wav" took 5 milliseconds Dec 30 12:22:39 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 30 12:22:39 volumio volumio[838]: info: ------------------------------ 5ms Dec 30 12:22:39 volumio volumio[838]: info: sendMpdCommand consume 1 took 2 milliseconds Dec 30 12:22:39 volumio volumio[838]: info: Dec 30 12:22:39 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:39 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:39 volumio volumio[838]: info: Dec 30 12:22:39 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:39 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:39 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:39 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:40 volumio volumio[838]: info: Dec 30 12:22:40 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:40 volumio volumio[838]: info: sendMpdCommand status took 741 milliseconds Dec 30 12:22:40 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:40 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:40 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:40 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand status took 491 milliseconds Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:41 volumio volumio[838]: info: Dec 30 12:22:41 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:41 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:41 volumio volumio[838]: info: Dec 30 12:22:41 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:41 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand playlistinfo took 551 milliseconds Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:41 volumio volumio[838]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 12:22:41 volumio volumio[838]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 12:22:41 volumio volumio[838]: info: ------------------------------ 1297ms Dec 30 12:22:41 volumio volumio[838]: info: ------------------------------ 556ms Dec 30 12:22:41 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:41 volumio volumio[838]: info: ------------------------------ 5ms Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand status took 5 milliseconds Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:41 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:41 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:41 volumio volumio[838]: 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} Dec 30 12:22:41 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:41 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:22:41 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:41 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:41 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:41 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:41 volumio volumio[838]: info: ------------------------------ 45ms Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:41 volumio volumio[838]: info: CorePlayQueue::getTrack 2 Dec 30 12:22:41 volumio volumio[838]: info: Prefetching next song Dec 30 12:22:41 volumio volumio[838]: info: DOING PREFETCH IN MPD Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/msg.wav" Dec 30 12:22:41 volumio volumio[838]: info: Dec 30 12:22:41 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:41 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Tencent/QQLite/Misc/Sound/Classic/msg.wav" took 2 milliseconds Dec 30 12:22:41 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 30 12:22:41 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:22:41 volumio volumio[838]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 30 12:22:43 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:22:43 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:43 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/Program Files/Thunder Dec 30 12:22:43 volumio volumio[838]: info: Exploding uri music-library/USB/B8A4218AA4214C66/Program Files/Thunder in service mpd Dec 30 12:22:43 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FThunder%2FData%2FThunderDownloader%2FXLGameBox%2FProgram%2Fring.wav&metadata=false Dec 30 12:22:43 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav Dec 30 12:22:43 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FThunder%2FProgram%2Fdownload-complete.wav&metadata=false Dec 30 12:22:43 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/Thunder/Program/download-complete.wav Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand stop took 0 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:22:43 volumio volumio[838]: info: Dec 30 12:22:43 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:43 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand clear took 2 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav" Dec 30 12:22:43 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:43 volumio volumio[838]: info: Dec 30 12:22:43 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:43 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:43 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav" took 1 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:22:43 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand play took 2 milliseconds Dec 30 12:22:43 volumio volumio[838]: info: Dec 30 12:22:43 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:43 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:43 volumio volumio[838]: info: Dec 30 12:22:43 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand status took 5 milliseconds Dec 30 12:22:43 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand status took 1 milliseconds Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:43 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ring.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav","trackType":"wav"} Dec 30 12:22:43 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:22:43 volumio volumio[838]: info: ------------------------------ 10ms Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:43 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ring.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/Thunder/Data/ThunderDownloader/XLGameBox/Program/ring.wav","trackType":"wav"} Dec 30 12:22:43 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:43 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:43 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:43 volumio volumio[838]: info: ------------------------------ 32ms Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:43 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:43 volumio volumio[838]: info: Prefetching next song Dec 30 12:22:43 volumio volumio[838]: info: DOING PREFETCH IN MPD Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Thunder/Program/download-complete.wav" Dec 30 12:22:43 volumio volumio[838]: info: Dec 30 12:22:43 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:43 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/Thunder/Program/download-complete.wav" took 39 milliseconds Dec 30 12:22:43 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 30 12:22:43 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:22:43 volumio volumio[838]: info: sendMpdCommand consume 1 took 2 milliseconds Dec 30 12:22:44 volumio volumio[838]: info: Dec 30 12:22:44 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:44 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:44 volumio volumio[838]: info: Dec 30 12:22:44 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:44 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:44 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:44 volumio volumio[838]: info: ------------------------------ 10ms Dec 30 12:22:44 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:22:44 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:44 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:44 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:44 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:44 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:44 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:44 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:44 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download-complete.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/Thunder/Program/download-complete.wav","trackType":"wav"} Dec 30 12:22:44 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:44 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:44 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:44 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:22:44 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:44 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:44 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:44 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:44 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:44 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:44 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:44 volumio volumio[838]: info: ------------------------------ 54ms Dec 30 12:22:45 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:22:45 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:45 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:45 volumio volumio[838]: info: CorePlayQueue::getTrack 2 Dec 30 12:22:45 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:45 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:45 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:45 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:46 volumio volumio[838]: info: Dec 30 12:22:46 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:46 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:46 volumio volumio[838]: info: Dec 30 12:22:46 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:46 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:46 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:46 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:46 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:46 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:22:46 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:46 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:46 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:46 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:46 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:46 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:46 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:46 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:46 volumio volumio[838]: 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} Dec 30 12:22:46 volumio volumio[838]: verbose: CURRENT POSITION 1 Dec 30 12:22:46 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:22:46 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:46 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:46 volumio volumio[838]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:22:46 volumio volumio[838]: info: ------------------------------ 30ms Dec 30 12:22:47 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:22:47 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:47 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/Program Files/KuGou2012 Dec 30 12:22:47 volumio volumio[838]: info: Exploding uri music-library/USB/B8A4218AA4214C66/Program Files/KuGou2012 in service mpd Dec 30 12:22:47 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2FProgram%20Files%2FKuGou2012%2Flogin.wav&metadata=false Dec 30 12:22:47 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand stop took 1 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:22:47 volumio volumio[838]: info: Dec 30 12:22:47 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:47 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand clear took 2 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav" Dec 30 12:22:47 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:47 volumio volumio[838]: info: Dec 30 12:22:47 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:47 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:47 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav" took 2 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:22:47 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:22:47 volumio volumio[838]: info: Dec 30 12:22:47 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand play took 7 milliseconds Dec 30 12:22:47 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:47 volumio volumio[838]: info: Dec 30 12:22:47 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:47 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand status took 15 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:47 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":2,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"login.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav","trackType":"wav"} Dec 30 12:22:47 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:22:47 volumio volumio[838]: info: ------------------------------ 23ms Dec 30 12:22:47 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:47 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:47 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":2,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"login.wav","artist":null,"album":null,"uri":"USB/B8A4218AA4214C66/Program Files/KuGou2012/login.wav","trackType":"wav"} Dec 30 12:22:47 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:47 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:47 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:47 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:47 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:47 volumio volumio[838]: info: ------------------------------ 34ms Dec 30 12:22:48 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:48 volumio volumio[838]: info: CorePlayQueue::getTrack 1 Dec 30 12:22:49 volumio volumio[838]: info: Dec 30 12:22:49 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:49 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:49 volumio volumio[838]: info: Dec 30 12:22:49 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:49 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:49 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:49 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:49 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:49 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:22:49 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:49 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:49 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:49 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:49 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:49 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:49 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:50 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:50 volumio volumio[838]: 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} Dec 30 12:22:50 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:50 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:22:50 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:50 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:50 volumio volumio[838]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:22:50 volumio volumio[838]: info: ------------------------------ 28ms Dec 30 12:22:55 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:55 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:22:55 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:22:55 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:55 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:55 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:22:55 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:55 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:55 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:22:55 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:22:55 volumio volumio[838]: info: Preload queue cleared Dec 30 12:22:55 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN Dec 30 12:22:55 volumio volumio[838]: info: Exploding uri music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN in service mpd Dec 30 12:22:56 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=Kat%20Pungdumri//extralarge&path=%2Fmnt%2FUSB%2FB8A4218AA4214C66%2F%24RECYCLE.BIN%2FS-1-5-21-883504350-4081300580-2658309558-1001%2F%24RIN89IQ%2F%E4%B8%8B%E8%BD%BD%E4%BF%9D%E5%AD%98%E7%9B%AE%E5%BD%95%2Ftouchpad_synaptics_16.2.5.0_x32.zip&metadata=false Dec 30 12:22:56 volumio volumio[838]: info: URI /mnt/USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand stop took 2 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:56 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand clear took 2 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" Dec 30 12:22:56 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:56 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" took 2 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand play took 1 milliseconds Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand status took 4 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand status took 3 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SynBlackScreen.wmv","artist":"Kat Pungdumri","album":null,"uri":"USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip/SynBlackScreen.wmv","trackType":"wmv"} Dec 30 12:22:56 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SynBlackScreen.wmv","artist":"Kat Pungdumri","album":null,"uri":"USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip/SynBlackScreen.wmv","trackType":"wmv"} Dec 30 12:22:56 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:56 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 31ms Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 46ms Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:22:56 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:22:56 volumio volumio[838]: info: Dec 30 12:22:56 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:22:56 volumio volumio[838]: error: updateQueue error: null Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 6ms Dec 30 12:22:56 volumio volumio[838]: info: sendMpdCommand status took 6 milliseconds Dec 30 12:22:56 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:22:56 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:56 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:22:56 volumio volumio[838]: 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} Dec 30 12:22:56 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:22:56 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:22:56 volumio volumio[838]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:22:56 volumio volumio[838]: info: ------------------------------ 51ms Dec 30 12:23:00 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:23:00 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:23:00 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:00 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:00 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:23:00 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:23:00 volumio volumio[838]: info: sendMpdCommand stop took 1 milliseconds Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:23:00 volumio volumio[838]: info: Dec 30 12:23:00 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:00 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:00 volumio volumio[838]: info: sendMpdCommand clear took 1 milliseconds Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" Dec 30 12:23:00 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:00 volumio volumio[838]: info: Dec 30 12:23:00 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:00 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:00 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:23:00 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" took 2 milliseconds Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:23:00 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:23:00 volumio volumio[838]: info: sendMpdCommand play took 2 milliseconds Dec 30 12:23:00 volumio volumio[838]: info: Dec 30 12:23:00 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:00 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:00 volumio volumio[838]: info: Dec 30 12:23:00 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:00 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:00 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:01 volumio volumio[838]: info: sendMpdCommand status took 618 milliseconds Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:23:01 volumio volumio[838]: info: Dec 30 12:23:01 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:01 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:01 volumio volumio[838]: info: Dec 30 12:23:01 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:01 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:01 volumio volumio[838]: info: sendMpdCommand status took 670 milliseconds Dec 30 12:23:01 volumio volumio[838]: info: sendMpdCommand playlistinfo took 54 milliseconds Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:23:01 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: 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} Dec 30 12:23:01 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: info: No code Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 12:23:01 volumio volumio[838]: info: ------------------------------ 724ms Dec 30 12:23:01 volumio volumio[838]: info: ------------------------------ 729ms Dec 30 12:23:01 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:01 volumio volumio[838]: info: ------------------------------ 58ms Dec 30 12:23:01 volumio volumio[838]: info: sendMpdCommand status took 58 milliseconds Dec 30 12:23:01 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:01 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: 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} Dec 30 12:23:01 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: info: No code Dec 30 12:23:01 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:01 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:01 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:01 volumio volumio[838]: info: ------------------------------ 112ms Dec 30 12:23:03 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:03 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:23:03 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:23:03 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:03 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN Dec 30 12:23:03 volumio volumio[838]: info: Using cached record of: music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN Dec 30 12:23:03 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:23:03 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:03 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:23:03 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:23:03 volumio volumio[838]: info: sendMpdCommand stop took 3 milliseconds Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:23:03 volumio volumio[838]: info: Dec 30 12:23:03 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:03 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:03 volumio volumio[838]: info: sendMpdCommand clear took 2 milliseconds Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" Dec 30 12:23:03 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:03 volumio volumio[838]: info: Dec 30 12:23:03 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:03 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:03 volumio volumio[838]: info: ------------------------------ 4ms Dec 30 12:23:03 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" took 2 milliseconds Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:23:03 volumio volumio[838]: info: ------------------------------ 4ms Dec 30 12:23:03 volumio volumio[838]: info: sendMpdCommand play took 3 milliseconds Dec 30 12:23:03 volumio volumio[838]: info: Dec 30 12:23:03 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:03 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:03 volumio volumio[838]: info: Dec 30 12:23:03 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:03 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:03 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:04 volumio volumio[838]: info: sendMpdCommand status took 618 milliseconds Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:23:04 volumio volumio[838]: info: Dec 30 12:23:04 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:04 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:04 volumio volumio[838]: info: Dec 30 12:23:04 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:04 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:04 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:04 volumio volumio[838]: info: sendMpdCommand status took 673 milliseconds Dec 30 12:23:04 volumio volumio[838]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 30 12:23:04 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:23:04 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:23:04 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:04 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: 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} Dec 30 12:23:04 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: info: No code Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 12:23:04 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: 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} Dec 30 12:23:04 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: info: No code Dec 30 12:23:04 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:04 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:04 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:04 volumio volumio[838]: info: ------------------------------ 752ms Dec 30 12:23:04 volumio volumio[838]: info: ------------------------------ 763ms Dec 30 12:23:04 volumio volumio[838]: info: ------------------------------ 82ms Dec 30 12:23:05 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:23:05 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:05 volumio volumio[838]: info: Adding Item to queue: music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN Dec 30 12:23:05 volumio volumio[838]: info: Using cached record of: music-library/USB/B8A4218AA4214C66/$RECYCLE.BIN Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::updateTrackBlock Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrackBlock Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPlay Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::play index 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::play index undefined Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::startPlaybackTimer Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::clearAddPlayTracks USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand stop took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:05 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand clear took 1 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" Dec 30 12:23:05 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:05 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 3ms Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand add "USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip" took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand play Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand play took 1 milliseconds Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"SynBlackScreen.wmv","artist":"Kat Pungdumri","album":null,"uri":"USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip/SynBlackScreen.wmv","trackType":"wmv"} Dec 30 12:23:05 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus stop Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 11ms Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::parseTrackInfo Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: verbose: STATE SERVICE {"status":"play","position":0,"seek":284,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"SynBlackScreen.wmv","artist":"Kat Pungdumri","album":null,"uri":"USB/B8A4218AA4214C66/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$RIN89IQ/下载保存目录/touchpad_synaptics_16.2.5.0_x32.zip/SynBlackScreen.wmv","trackType":"wmv"} Dec 30 12:23:05 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState stateService play Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:23:05 volumio volumio[838]: info: Received an update from plugin. extracting info from payload Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 44ms Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces system playlist update Dec 30 12:23:05 volumio volumio[838]: info: Ignoring MPD Status Update Dec 30 12:23:05 volumio volumio[838]: info: Dec 30 12:23:05 volumio volumio[838]: ---------------------------- MPD announces state update: player Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::getState Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::sendMpdCommand status Dec 30 12:23:05 volumio volumio[838]: error: updateQueue error: null Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 2ms Dec 30 12:23:05 volumio volumio[838]: info: sendMpdCommand status took 2 milliseconds Dec 30 12:23:05 volumio volumio[838]: verbose: ControllerMpd::parseState Dec 30 12:23:05 volumio volumio[838]: info: ControllerMpd::pushState Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::servicePushState Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::pushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:05 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:05 volumio volumio[838]: 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} Dec 30 12:23:05 volumio volumio[838]: verbose: CURRENT POSITION 0 Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState stateService stop Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::syncState currentStatus play Dec 30 12:23:05 volumio volumio[838]: info: CoreCommandRouter::volumioPushState Dec 30 12:23:05 volumio volumio[838]: info: CoreStateMachine::stPlaybackTimer Dec 30 12:23:05 volumio volumio[838]: info: ------------------------------ 34ms Dec 30 12:23:10 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 30 12:23:10 volumio volumio[838]: info: CURURI: music-library/USB/DAE81B97E81B70CD Dec 30 12:23:11 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:14 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:14 volumio volumio[838]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 12:23:14 volumio volumio[838]: info: CoreStateMachine::ClearQueue Dec 30 12:23:14 volumio volumio[838]: info: CoreStateMachine::stop Dec 30 12:23:14 volumio volumio[838]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 12:23:14 volumio volumio[838]: info: CorePlayQueue::clearPlayQueue Dec 30 12:23:14 volumio volumio[838]: info: CorePlayQueue::saveQueue Dec 30 12:23:14 volumio volumio[838]: info: CoreCommandRouter::volumioPushQueue Dec 30 12:23:14 volumio volumio[838]: info: CoreStateMachine::addQueueItems Dec 30 12:23:14 volumio volumio[838]: info: CorePlayQueue::addQueueItems Dec 30 12:23:14 volumio volumio[838]: info: Preload queue cleared Dec 30 12:23:14 volumio volumio[838]: info: Adding Item to queue: music-library/USB/DAE81B97E81B70CD/$RECYCLE.BIN Dec 30 12:23:14 volumio volumio[838]: info: Exploding uri music-library/USB/DAE81B97E81B70CD/$RECYCLE.BIN in service mpd Dec 30 12:23:25 volumio volumio-remote-updater[572]: [2025-12-30 12:23:25] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Dec 30 12:23:30 volumio volumio-remote-updater[572]: [2025-12-30 12:23:30] [connect] Successful connection Dec 30 12:23:36 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 30 12:23:36 volumio volumio[838]: info: CURURI: music-library/USB/DAE81B97E81B70CD/抓轨 Dec 30 12:23:36 volumio volumio[838]: verbose: New Socket.io Connection to 192.168.3.159:3000 from 192.168.3.150 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 30 12:23:36 volumio volumio[838]: verbose: New Socket.io Connection to 192.168.3.159:3000 from 192.168.3.150 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 30 12:23:36 volumio volumio[838]: verbose: New Socket.io Connection to 192.168.3.159:3000 from 192.168.3.150 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 30 12:23:37 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 12:23:37 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 12:23:37 volumio volumio[838]: info: Discovery: Getting this device information Dec 30 12:23:37 volumio volumio[838]: info: CoreCommandRouter::volumioGetState Dec 30 12:23:37 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:37 volumio volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 12:23:37 volumio volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 30 12:23:37 volumio volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 30 12:23:38 volumio volumio[838]: info: CoreCommandRouter::volumioGetState Dec 30 12:23:38 volumio volumio[838]: info: CorePlayQueue::getTrack 0 Dec 30 12:23:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FDAE81B97E81B70CD%2F%24RECYCLE.BIN%2FS-1-5-21-883504350-4081300580-2658309558-1001%2F%24R7ZZSV7%2FWeChat%20Files%2Fqq_54690716%2FFileStorage%2FFile%2F2023-12%2F%E6%96%B0%E5%BD%95%E9%9F%B3%20122.m4a&metadata=false Dec 30 12:23:38 volumio volumio[838]: info: URI /mnt/USB/DAE81B97E81B70CD/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$R7ZZSV7/WeChat Files/qq_54690716/FileStorage/File/2023-12/新录音 122.m4a Dec 30 12:23:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FDAE81B97E81B70CD%2F%24RECYCLE.BIN%2FS-1-5-21-883504350-4081300580-2658309558-1001%2F%24R7ZZSV7%2FWeChat%20Files%2Fwxid_233qtbv7n57s22%2FFileStorage%2FFile%2F2024-01%2F20240104_214942.m4a&metadata=false Dec 30 12:23:38 volumio volumio[838]: info: URI /mnt/USB/DAE81B97E81B70CD/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$R7ZZSV7/WeChat Files/wxid_233qtbv7n57s22/FileStorage/File/2024-01/20240104_214942.m4a Dec 30 12:23:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FDAE81B97E81B70CD%2F%24RECYCLE.BIN%2FS-1-5-21-883504350-4081300580-2658309558-1001%2F%24R7ZZSV7%2FWeChat%20Files%2Fwxid_233qtbv7n57s22%2FFileStorage%2FFile%2F2024-02%2F8.2%E5%85%B1%E5%9C%86%E4%B8%AD%E5%9B%BD%E6%A2%A6%EF%BC%88%E5%90%AB%E8%A7%86%E9%A2%91%EF%BC%89-%E3%80%90%E7%94%9F%E5%8A%A8%E8%AF%BE%E5%A0%82%E3%80%912023-2024%E5%AD%A6%E5%B9%B4%E4%B9%9D%E5%B9%B4%E7%BA%A7%E9%81%93%E5%BE%B7%E4%B8%8E%E6%B3%95%E6%B2%BB%E4%B8%8A%E5%86%8C%E5%90%8C%E6%AD%A5%E5%A4%87%E8%AF%BE%E7%B2%BE%E5%88%B6%E8%AF%BE%E4%BB%B6%EF%BC%88%E9%83%A8%E7%BC%96%E7%89%88%EF%BC%89.zip&metadata=false Dec 30 12:23:38 volumio volumio[838]: info: URI /mnt/USB/DAE81B97E81B70CD/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$R7ZZSV7/WeChat Files/wxid_233qtbv7n57s22/FileStorage/File/2024-02/8.2共圆中国梦（含视频）-【生动课堂】2023-2024学年九年级道德与法治上册同步备课精制课件（部编版）.zip Dec 30 12:23:38 volumio volumio[838]: info: ALBUMART /albumart?cacheid=4&web=//extralarge&path=%2Fmnt%2FUSB%2FDAE81B97E81B70CD%2F%24RECYCLE.BIN%2FS-1-5-21-883504350-4081300580-2658309558-1001%2F%24R7ZZSV7%2FWeChat%20Files%2Fwxid_233qtbv7n57s22%2FFileStorage%2FFile%2F2024-02%2F8.2%E5%85%B1%E5%9C%86%E4%B8%AD%E5%9B%BD%E6%A2%A6%EF%BC%88%E5%90%AB%E8%A7%86%E9%A2%91%EF%BC%89-%E3%80%90%E7%94%9F%E5%8A%A8%E8%AF%BE%E5%A0%82%E3%80%912023-2024%E5%AD%A6%E5%B9%B4%E4%B9%9D%E5%B9%B4%E7%BA%A7%E9%81%93%E5%BE%B7%E4%B8%8E%E6%B3%95%E6%B2%BB%E4%B8%8A%E5%86%8C%E5%90%8C%E6%AD%A5%E5%A4%87%E8%AF%BE%E7%B2%BE%E5%88%B6%E8%AF%BE%E4%BB%B6%EF%BC%88%E9%83%A8%E7%BC%96%E7%89%88%EF%BC%89.zip&metadata=false Dec 30 12:23:38 volumio volumio[838]: info: URI /mnt/USB/DAE81B97E81B70CD/$RECYCLE.BIN/S-1-5-21-883504350-4081300580-2658309558-1001/$R7ZZSV7/WeChat Files/wxid_233qtbv7n57s22/FileStorage/File/2024-02/8.2共圆中国梦（含视频）-【生动课堂】2023-2024学年九年级道德与法治上册同步备课精制课件（部编版）.zip Dec 30 12:23:38 volumio volumio[838]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:23:38 volumio volumio[838]: Error: Unable to resolve or reject the same promise twice Dec 30 12:23:38 volumio volumio[838]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 30 12:23:38 volumio volumio[838]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 30 12:23:38 volumio volumio[838]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 30 12:23:38 volumio volumio[838]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 30 12:23:38 volumio volumio[838]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 30 12:23:38 volumio volumio[838]: at Socket.emit (events.js:400:28) Dec 30 12:23:38 volumio volumio[838]: at addChunk (internal/streams/readable.js:293:12) Dec 30 12:23:38 volumio volumio[838]: at readableAddChunk (internal/streams/readable.js:263:11) Dec 30 12:23:38 volumio volumio[838]: at Socket.Readable.push (internal/streams/readable.js:206:10) Dec 30 12:23:38 volumio volumio[838]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 30 12:23:38 volumio volumio[838]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 12:23:38 volumio sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 12:22 Dec 30 12:23:38 volumio sudo[3942]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"