-- Logs begin at Fri 2025-05-02 08:22:40 CST, end at Fri 2025-05-02 08:52:09 CST. -- May 02 08:51:01 volumio-x86 volumio[1100]: info: MyVolumio login type: Token May 02 08:51:01 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 08:51:01 volumio-x86 volumio[1100]: info: CURURI: music-library/INTERNAL/musics/光明谭艳 May 02 08:51:01 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:01 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:01 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 in service mpd May 02 08:51:01 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=Unknown%20Singer/%E5%85%89%E6%98%8E%E8%B0%AD%E8%89%B3/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%85%89%E6%98%8E%E8%B0%AD%E8%89%B3%2FUnknown%20Singer-%E5%85%89%E6%98%8E.mp3&metadata=false May 02 08:51:01 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:05 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::ClearQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::stPlaybackTimer May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::serviceStop May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::serviceStop May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::stop May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::clearPlayQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::addQueueItems May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::addQueueItems May 02 08:51:05 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:05 volumio-x86 volumio[1100]: info: Adding Item to queue: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:05 volumio-x86 volumio[1100]: info: Using cached record of: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPlay May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::play index 0 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::play index undefined May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 58 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 17 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:05 volumio-x86 volumio[1100]: info: May 02 08:51:05 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:05 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand status took 29 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand clear took 27 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand status took 25 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand status took 19 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand status took 14 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: info: sendMpdCommand status took 11 milliseconds May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:05 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:05 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:05 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:05 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: 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} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: No code May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: 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} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: No code May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: 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} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: No code May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: 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} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: No code May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 219ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 218ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 219ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 219ms May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: error: updateQueue error: null May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 246ms May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 240 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" took 240 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 32ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 28ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 26ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 18ms May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 02 08:51:06 volumio-x86 volumio[1100]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 287ms May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:06 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 216ms May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand play took 201 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 199ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 199ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 190ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 188ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 47 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 41 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 40 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 34 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 31 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 27 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: May 02 08:51:06 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 74 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 73 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 74 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 72 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 71 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 73 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 65 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 62 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 58 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand status took 54 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 291ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 343ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 333ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 330ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 326ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 324ms May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 229 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 229 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 226 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 224 milliseconds May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":910,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":910,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":910,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:06 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:06 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:06 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:06 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 473ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 470ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 465ms May 02 08:51:06 volumio-x86 volumio[1100]: info: ------------------------------ 462ms May 02 08:51:09 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioGetState May 02 08:51:09 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:09 volumio-x86 sudo[9999]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 02 08:51:09 volumio-x86 sudo[9999]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:51:11 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::ClearQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::stPlaybackTimer May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::serviceStop May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::serviceStop May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::stop May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::clearPlayQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::addQueueItems May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::addQueueItems May 02 08:51:11 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:11 volumio-x86 volumio[1100]: info: Adding Item to queue: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:11 volumio-x86 volumio[1100]: info: Using cached record of: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPlay May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::play index 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::play index undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 97 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 28 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand status took 81 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand clear took 76 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand status took 56 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand status took 53 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand status took 45 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand status took 43 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: 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} May 02 08:51:11 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: No code May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: 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} May 02 08:51:11 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: No code May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: 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} May 02 08:51:11 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: No code May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: 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} May 02 08:51:11 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: No code May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:11 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:11 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 315ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 296ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 289ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 288ms May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: error: updateQueue error: null May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 276ms May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 267 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" took 268 milliseconds May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 27ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 23ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 20ms May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 18ms May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:11 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 02 08:51:11 volumio-x86 volumio[1100]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 08:51:11 volumio-x86 volumio[1100]: info: ------------------------------ 368ms May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:11 volumio-x86 volumio[1100]: info: May 02 08:51:11 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:11 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 217ms May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand play took 205 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 200ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 199ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 178ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 177ms May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 35 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 36 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 34 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 32 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 27 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::ClearQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::clearPlayQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::addQueueItems May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::addQueueItems May 02 08:51:12 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:12 volumio-x86 volumio[1100]: info: Adding Item to queue: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:12 volumio-x86 volumio[1100]: info: Using cached record of: music-library/INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPlay May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::play index 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::play index undefined May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 121 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 101 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 106 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 104 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 103 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 103 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 98 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 97 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 281ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 376ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 373ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 375ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 369ms May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 404 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 385 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 375 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 375 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 647ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 620ms May 02 08:51:12 volumio-x86 volumio[1100]: info: ------------------------------ 616ms May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:12 volumio-x86 volumio[1100]: info: May 02 08:51:12 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:12 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 165 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand clear took 161 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 30 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 26 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 20 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: info: sendMpdCommand status took 14 milliseconds May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" May 02 08:51:12 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: 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} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::stPlaybackTimer May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: 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} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: No code May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: 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} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: No code May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: 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} May 02 08:51:12 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:12 volumio-x86 volumio[1100]: info: No code May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:12 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:12 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 199ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 198ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 194ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 191ms May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: error: updateQueue error: null May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 379ms May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 366 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand add "INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3" took 365 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 190ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 182ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 180ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 171ms May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 02 08:51:13 volumio-x86 volumio[1100]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 573ms May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:13 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 74ms May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand play took 59 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 30ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 28ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 24ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 20ms May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: May 02 08:51:13 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 42 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 41 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 31 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 27 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 22 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand status took 19 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 28 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 27 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 25 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 24 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 23 milliseconds May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:13 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:13 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:13 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:13 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:13 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:13 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:13 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:13 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:13 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 226ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 335ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 326ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 323ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 317ms May 02 08:51:13 volumio-x86 volumio[1100]: info: ------------------------------ 321ms May 02 08:51:18 volumio-x86 volumio[1100]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 May 02 08:51:18 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioGetState May 02 08:51:18 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:31 volumio-x86 volumio[1100]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 02 08:51:31 volumio-x86 volumio[1100]: info: Retrying Login Due to Network Error May 02 08:51:32 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 08:51:32 volumio-x86 volumio[1100]: info: CURURI: music-library May 02 08:51:32 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:32 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 08:51:32 volumio-x86 volumio[1100]: info: CURURI: music-library/INTERNAL May 02 08:51:32 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:33 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 08:51:33 volumio-x86 volumio[1100]: info: CURURI: music-library/INTERNAL/musics May 02 08:51:33 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:41 volumio-x86 volumio[1100]: info: MyVolumio login type: Token May 02 08:51:42 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 08:51:42 volumio-x86 volumio[1100]: info: CURURI: music-library/INTERNAL/musics/古筝遇见童丽II May 02 08:51:42 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-古老的故事.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-天空之城(吟唱版).mp3 May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-如梦如烟.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-春花秋月何时了.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-水上人.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-相思.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-离人愁.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-笑红尘.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-落花流水.mp3 May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-门前杨柳迎风摆.flac May 02 08:51:42 volumio-x86 volumio[1100]: info: Preloading song: music-library/INTERNAL/musics/古筝遇见童丽II/童丽-雨蝶.mp3 May 02 08:51:42 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 in service mpd May 02 08:51:42 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%8D%B7%E7%8F%A0%E5%B8%98.mp3&metadata=false May 02 08:51:42 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 May 02 08:51:42 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-古老的故事.flac in service mpd May 02 08:51:42 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%8F%A4%E8%80%81%E7%9A%84%E6%95%85%E4%BA%8B.flac&metadata=false May 02 08:51:42 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-古老的故事.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-天空之城(吟唱版).mp3 in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%A4%A9%E7%A9%BA%E4%B9%8B%E5%9F%8E(%E5%90%9F%E5%94%B1%E7%89%88).mp3&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-天空之城(吟唱版).mp3 May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-如梦如烟.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%A6%82%E6%A2%A6%E5%A6%82%E7%83%9F.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-如梦如烟.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-春花秋月何时了.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E6%98%A5%E8%8A%B1%E7%A7%8B%E6%9C%88%E4%BD%95%E6%97%B6%E4%BA%86.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-春花秋月何时了.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-水上人.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E6%B0%B4%E4%B8%8A%E4%BA%BA.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-水上人.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-相思.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%9B%B8%E6%80%9D.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-相思.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-离人愁.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%A6%BB%E4%BA%BA%E6%84%81.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-离人愁.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-笑红尘.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%AC%91%E7%BA%A2%E5%B0%98.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-笑红尘.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-落花流水.mp3 in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E8%90%BD%E8%8A%B1%E6%B5%81%E6%B0%B4.mp3&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-落花流水.mp3 May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-门前杨柳迎风摆.flac in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E9%97%A8%E5%89%8D%E6%9D%A8%E6%9F%B3%E8%BF%8E%E9%A3%8E%E6%91%86.flac&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-门前杨柳迎风摆.flac May 02 08:51:43 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II/童丽-雨蝶.mp3 in service mpd May 02 08:51:43 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E9%9B%A8%E8%9D%B6.mp3&metadata=false May 02 08:51:43 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-雨蝶.mp3 May 02 08:51:48 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::ClearQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::stPlaybackTimer May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::serviceStop May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::serviceStop May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::stop May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::clearPlayQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::addQueueItems May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::addQueueItems May 02 08:51:48 volumio-x86 volumio[1100]: info: Preload queue cleared May 02 08:51:48 volumio-x86 volumio[1100]: info: Adding Item to queue: music-library/INTERNAL/musics/古筝遇见童丽II May 02 08:51:48 volumio-x86 volumio[1100]: info: Exploding uri music-library/INTERNAL/musics/古筝遇见童丽II in service mpd May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 76 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%8D%B7%E7%8F%A0%E5%B8%98.mp3&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%8F%A4%E8%80%81%E7%9A%84%E6%95%85%E4%BA%8B.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-古老的故事.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%A4%A9%E7%A9%BA%E4%B9%8B%E5%9F%8E(%E5%90%9F%E5%94%B1%E7%89%88).mp3&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-天空之城(吟唱版).mp3 May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E5%A6%82%E6%A2%A6%E5%A6%82%E7%83%9F.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-如梦如烟.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E6%98%A5%E8%8A%B1%E7%A7%8B%E6%9C%88%E4%BD%95%E6%97%B6%E4%BA%86.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-春花秋月何时了.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E6%B0%B4%E4%B8%8A%E4%BA%BA.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-水上人.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%9B%B8%E6%80%9D.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-相思.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%A6%BB%E4%BA%BA%E6%84%81.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-离人愁.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E7%AC%91%E7%BA%A2%E5%B0%98.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-笑红尘.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E8%90%BD%E8%8A%B1%E6%B5%81%E6%B0%B4.mp3&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-落花流水.mp3 May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E9%97%A8%E5%89%8D%E6%9D%A8%E6%9F%B3%E8%BF%8E%E9%A3%8E%E6%91%86.flac&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-门前杨柳迎风摆.flac May 02 08:51:48 volumio-x86 volumio[1100]: info: ALBUMART /albumart?cacheid=373&web=%E7%AB%A5%E4%B8%BD/%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusics%2F%E5%8F%A4%E7%AD%9D%E9%81%87%E8%A7%81%E7%AB%A5%E4%B8%BDII%2F%E7%AB%A5%E4%B8%BD-%E9%9B%A8%E8%9D%B6.mp3&metadata=false May 02 08:51:48 volumio-x86 volumio[1100]: info: URI /mnt/INTERNAL/musics/古筝遇见童丽II/童丽-雨蝶.mp3 May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand status took 57 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand status took 54 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand status took 50 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand status took 47 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::saveQueue May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::updateTrackBlock May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrackBlock May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPlay May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::play index 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::play index undefined May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand status took 91 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 85 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 86 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 88 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand stop took 34 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: 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":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:48 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: No code May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: 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":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:48 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: No code May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: 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":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:48 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: No code May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: 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":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:48 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: No code May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 372ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 372ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 371ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 369ms May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 429 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand clear took 421 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3" May 02 08:51:48 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: 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":"光明","artist":"Unknown Singer","album":"光明谭艳","uri":"INTERNAL/musics/光明谭艳/Unknown Singer-光明.mp3","trackType":"mp3"} May 02 08:51:48 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: No code May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:48 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:48 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 666ms May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: error: updateQueue error: null May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 142ms May 02 08:51:48 volumio-x86 volumio[1100]: info: sendMpdCommand add "INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3" took 137 milliseconds May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 35ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 32ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 26ms May 02 08:51:48 volumio-x86 volumio[1100]: info: ------------------------------ 25ms May 02 08:51:48 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:48 volumio-x86 volumio[1100]: info: May 02 08:51:48 volumio-x86 volumio[1100]: ---------------------------- MPD announces system playlist update May 02 08:51:48 volumio-x86 volumio[1100]: info: Ignoring MPD Status Update May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 204ms May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand play took 197 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 151ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 143ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 132ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 133ms May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 sudo[9999]: pam_unix(sudo:session): session closed for user root May 02 08:51:49 volumio-x86 volumio[1100]: info: May 02 08:51:49 volumio-x86 volumio[1100]: ---------------------------- MPD announces state update: player May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::getState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 85 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 87 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 34 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 31 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 30 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand status took 60 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 42 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 45 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 45 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 40 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseState May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:49 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:49 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:49 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:49 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 272ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 315ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 257ms May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 252ms May 02 08:51:49 volumio-x86 volumio[1100]: info: sendMpdCommand playlistinfo took 202 milliseconds May 02 08:51:49 volumio-x86 volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 02 08:51:49 volumio-x86 volumio[1100]: info: ControllerMpd::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::servicePushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} May 02 08:51:49 volumio-x86 volumio[1100]: verbose: CURRENT POSITION 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState stateService play May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 02 08:51:49 volumio-x86 volumio[1100]: info: Received an update from plugin. extracting info from payload May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreStateMachine::pushState May 02 08:51:49 volumio-x86 volumio[1100]: info: CorePlayQueue::getTrack 0 May 02 08:51:49 volumio-x86 volumio[1100]: info: CoreCommandRouter::volumioPushState May 02 08:51:49 volumio-x86 volumio[1100]: info: ------------------------------ 338ms May 02 08:52:07 volumio-x86 volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:52:07 volumio-x86 volumio[1100]: Error: connect ETIMEDOUT 108.160.167.159:80 May 02 08:52:07 volumio-x86 volumio[1100]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 02 08:52:07 volumio-x86 volumio[1100]: errno: -110, May 02 08:52:07 volumio-x86 volumio[1100]: code: 'ETIMEDOUT', May 02 08:52:07 volumio-x86 volumio[1100]: syscall: 'connect', May 02 08:52:07 volumio-x86 volumio[1100]: address: '108.160.167.159', May 02 08:52:07 volumio-x86 volumio[1100]: port: 80 May 02 08:52:07 volumio-x86 volumio[1100]: } May 02 08:52:07 volumio-x86 volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:52:07 volumio-x86 nmbd[814]: [2025/05/02 08:52:07.946015, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 02 08:52:07 volumio-x86 nmbd[814]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 02 08:52:09 volumio-x86 sudo[10224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 08:51 May 02 08:52:09 volumio-x86 sudo[10224]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"