-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Mon 2024-08-19 15:18:49 CST. -- Aug 19 15:17:00 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioNext Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::next Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::stop Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::stPlaybackTimer Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 638 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::serviceStop Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 638 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::serviceStop Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::stop Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand stop took 19 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::play index undefined Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::startPlaybackTimer Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::clearAddPlayTracks USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand stop took 9 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 3 milliseconds Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand clear took 6 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand add "USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac" Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 17ms Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: error: updateQueue error: null Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 12 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 11ms Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand add "USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac" took 8 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 5ms Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand play Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: 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} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: No code Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 69ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 75ms Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 73ms Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand play took 70 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 7ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 5ms Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:03 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 10ms Aug 19 15:17:03 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 28 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 25 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 6 milliseconds Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: Aug 19 15:17:03 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 17 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 19 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand status took 16 milliseconds Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:03 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:03 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 101ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 298ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 296ms Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 254 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 251 milliseconds Aug 19 15:17:03 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 251 milliseconds Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:03 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:03 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":276,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"追梦","artist":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:03 volumio volumio[1898]: verbose: CURRENT POSITION 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:03 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:03 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:03 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 392ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 382ms Aug 19 15:17:03 volumio volumio[1898]: info: ------------------------------ 380ms Aug 19 15:17:04 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:17:05 volumio volumio[1898]: info: CoreCommandRouter::volumioNext Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::next Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::stop Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::stPlaybackTimer Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:05 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:05 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:05 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:05 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:05 volumio volumio[1898]: info: CoreStateMachine::serviceStop Aug 19 15:17:05 volumio volumio[1898]: info: CorePlayQueue::getTrack 515 Aug 19 15:17:05 volumio volumio[1898]: info: CoreCommandRouter::serviceStop Aug 19 15:17:05 volumio volumio[1898]: info: ControllerMpd::stop Aug 19 15:17:05 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand stop took 51 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::play index undefined Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::startPlaybackTimer Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::clearAddPlayTracks USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand stop took 3 milliseconds Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand clear took 11 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 6 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 4 milliseconds Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand add "USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac" Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: 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":"吴奇隆","album":"一天一天等下去","uri":"USB/351BE32E0EBA7B10/music/吴奇隆 - 一天一天等下去/吴奇隆 - 追梦.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: No code Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: 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} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: No code Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: 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} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: No code Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 153ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 144ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 140ms Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: error: updateQueue error: null Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 150ms Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand add "USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac" took 147 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 11ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 10ms Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand play Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:06 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 31ms Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand play took 28 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 26ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 25ms Aug 19 15:17:06 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 11 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 4 milliseconds Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: Aug 19 15:17:06 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 13 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 9 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:06 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:06 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 73ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 198ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 197ms Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 181 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 179 milliseconds Aug 19 15:17:06 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 178 milliseconds Aug 19 15:17:06 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:06 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:06 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"浮躁","artist":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:06 volumio volumio[1898]: verbose: CURRENT POSITION 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:06 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:06 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:06 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 260ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 253ms Aug 19 15:17:06 volumio volumio[1898]: info: ------------------------------ 250ms Aug 19 15:17:08 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioNext Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::next Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::stPlaybackTimer Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::serviceStop Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1811 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::serviceStop Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::stop Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand stop took 43 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::play index undefined Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::startPlaybackTimer Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::clearAddPlayTracks USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand stop took 2 milliseconds Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:09 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand clear took 7 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand status took 5 milliseconds Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand add "USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac" Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: 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":"王菲","album":"情・菲・得意","uri":"USB/351BE32E0EBA7B10/music/王菲 - 情菲得意/王菲 - 浮躁.flac","trackType":"flac"} Aug 19 15:17:09 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: No code Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: 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} Aug 19 15:17:09 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: No code Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: ------------------------------ 106ms Aug 19 15:17:09 volumio volumio[1898]: info: ------------------------------ 97ms Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:09 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:09 volumio volumio[1898]: error: updateQueue error: null Aug 19 15:17:09 volumio volumio[1898]: info: Aug 19 15:17:09 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:09 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand status took 99 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: ------------------------------ 99ms Aug 19 15:17:09 volumio volumio[1898]: info: sendMpdCommand add "USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac" took 96 milliseconds Aug 19 15:17:09 volumio volumio[1898]: info: ------------------------------ 4ms Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:09 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand play Aug 19 15:17:09 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: 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} Aug 19 15:17:09 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:09 volumio volumio[1898]: info: No code Aug 19 15:17:09 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:09 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:09 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 156ms Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:10 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:10 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 91ms Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand play took 88 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 33ms Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 31ms Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:10 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:10 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 120ms Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 6 milliseconds Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 13 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 15 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 11 milliseconds Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2475 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2475 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:10 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 53ms Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 71ms Aug 19 15:17:10 volumio volumio[1898]: info: Aug 19 15:17:10 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 52 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand status took 4 milliseconds Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2475 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:10 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2475 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:10 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2475 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:10 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 191ms Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 189ms Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 185ms Aug 19 15:17:10 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 141 milliseconds Aug 19 15:17:10 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:10 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":285,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2502 Kbps","isStreaming":false,"title":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:10 volumio volumio[1898]: verbose: CURRENT POSITION 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:10 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:10 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:10 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:10 volumio volumio[1898]: info: ------------------------------ 189ms Aug 19 15:17:11 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioNext Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::next Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::stPlaybackTimer Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::serviceStop Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1747 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::serviceStop Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::stop Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand stop took 20 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::play index undefined Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::startPlaybackTimer Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::clearAddPlayTracks USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand status took 3 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand stop took 2 milliseconds Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand clear took 7 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand status took 4 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand status took 2 milliseconds Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand add "USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac" Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: 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":"Oath","artist":"Faye Wong","album":"No Faye No Live!","uri":"USB/351BE32E0EBA7B10/music/王 菲----菲比寻常 SACD-24bit-88khz/DISC1/1.03. Oath.flac","trackType":"flac"} Aug 19 15:17:13 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: No code Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: 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} Aug 19 15:17:13 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: No code Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: 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} Aug 19 15:17:13 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: No code Aug 19 15:17:13 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:13 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:13 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 92ms Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 88ms Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 84ms Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: error: updateQueue error: null Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 87ms Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand add "USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac" took 87 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 4ms Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 3ms Aug 19 15:17:13 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand play Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: info: Aug 19 15:17:13 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:13 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 18ms Aug 19 15:17:13 volumio volumio[1898]: info: sendMpdCommand play took 17 milliseconds Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 16ms Aug 19 15:17:13 volumio volumio[1898]: info: ------------------------------ 15ms Aug 19 15:17:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 10 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 7 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 5 milliseconds Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: Aug 19 15:17:14 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 14 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 8 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand status took 6 milliseconds Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1769 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1769 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:14 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1773 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:14 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 62ms Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 112ms Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 111ms Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 92 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 90 milliseconds Aug 19 15:17:14 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 91 milliseconds Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1760 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:14 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1775 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:14 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1775 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:14 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:14 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:14 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:14 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 242ms Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 235ms Aug 19 15:17:14 volumio volumio[1898]: info: ------------------------------ 234ms Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPrevious Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::previous Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::seek Aug 19 15:17:18 volumio volumio[1898]: error: null Aug 19 15:17:18 volumio volumio[1898]: info: Aug 19 15:17:18 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:18 volumio volumio[1898]: info: Aug 19 15:17:18 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:18 volumio volumio[1898]: info: Aug 19 15:17:18 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand status took 9 milliseconds Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand status took 5 milliseconds Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand status took 5 milliseconds Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2545 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:18 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:18 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1769 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:18 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:18 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: ------------------------------ 71ms Aug 19 15:17:18 volumio volumio[1898]: info: ------------------------------ 69ms Aug 19 15:17:18 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 57 milliseconds Aug 19 15:17:18 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:18 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":265,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1769 Kbps","isStreaming":false,"title":"天空","artist":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:18 volumio volumio[1898]: verbose: CURRENT POSITION 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:18 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:18 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:18 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:18 volumio volumio[1898]: info: ------------------------------ 94ms Aug 19 15:17:21 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::volumioNext Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::next Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::stop Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::stPlaybackTimer Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::serviceStop Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 1730 Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::serviceStop Aug 19 15:17:21 volumio volumio[1898]: info: ControllerMpd::stop Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand stop took 19 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::play index undefined Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::startPlaybackTimer Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::clearAddPlayTracks USB/351BE32E0EBA7B10/music/12.矜持.wav Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::updateTrackBlock Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrackBlock Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:21 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:21 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand status took 20 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand stop took 5 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand status took 4 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand status took 2 milliseconds Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand clear took 3 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand add "USB/351BE32E0EBA7B10/music/12.矜持.wav" Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:21 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: 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":"王菲","album":"天空 SACD","uri":"USB/351BE32E0EBA7B10/music/王 菲----天空 SACD-24bit-88khz/01 - 天空.flac","trackType":"flac"} Aug 19 15:17:21 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::syncState stateService stop Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:21 volumio volumio[1898]: info: No code Aug 19 15:17:21 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:21 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:21 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:21 volumio volumio[1898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 15:17:21 volumio volumio[1898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 53ms Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 35ms Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: error: updateQueue error: null Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 36ms Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand add "USB/351BE32E0EBA7B10/music/12.矜持.wav" took 35 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 8ms Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 4ms Aug 19 15:17:21 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand play Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: info: Aug 19 15:17:21 volumio volumio[1898]: ---------------------------- MPD announces system playlist update Aug 19 15:17:21 volumio volumio[1898]: info: Ignoring MPD Status Update Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 10ms Aug 19 15:17:21 volumio volumio[1898]: info: sendMpdCommand play took 8 milliseconds Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 6ms Aug 19 15:17:21 volumio volumio[1898]: info: ------------------------------ 5ms Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 18 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 18 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 16 milliseconds Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: Aug 19 15:17:22 volumio volumio[1898]: ---------------------------- MPD announces state update: player Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::getState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand status Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 8 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 5 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand status took 3 milliseconds Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseState Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus stop Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:22 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:22 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 49ms Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 75ms Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 79ms Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 19 15:17:22 volumio volumio[1898]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: verbose: ControllerMpd::parseTrackInfo Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:22 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:22 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: ControllerMpd::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::servicePushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12.矜持.wav","artist":null,"album":null,"uri":"USB/351BE32E0EBA7B10/music/12.矜持.wav","trackType":"wav"} Aug 19 15:17:22 volumio volumio[1898]: verbose: CURRENT POSITION 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState stateService play Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::syncState currentStatus play Aug 19 15:17:22 volumio volumio[1898]: info: Received an update from plugin. extracting info from payload Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:17:22 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:17:22 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 213ms Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 209ms Aug 19 15:17:22 volumio volumio[1898]: info: ------------------------------ 209ms Aug 19 15:17:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:17:26 volumio volumio[1898]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 15:17:26 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 19 15:17:26 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 19 15:17:26 volumio sudo[2205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 19 15:17:26 volumio sudo[2205]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 19 15:17:26 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio sudo[2205]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 19 15:17:26 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 19 15:17:26 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 19 15:17:26 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 19 15:17:26 volumio sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 15:17:26 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 15:17:26 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:26 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:26 volumio sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 19 15:17:26 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 15:17:27 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Aug 19 15:17:27 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 15:17:47 volumio volumio[1898]: info: Getting Alsa Cards List without I2S DAC Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 19 15:17:47 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 19 15:17:50 volumio volumio[1898]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 15:17:50 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 15:17:50 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 15:17:50 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 15:17:51 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 19 15:17:51 volumio volumio[1898]: info: Received Get System Version Aug 19 15:17:51 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 15:17:51 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Aug 19 15:17:55 volumio volumio[1898]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 15:17:56 volumio volumio[1898]: info: Getting Alsa Cards List without I2S DAC Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 19 15:17:56 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 19 15:17:58 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:18:05 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:18:21 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:18:25 volumio volumio[1898]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::volumioGetState Aug 19 15:18:25 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:18:25 volumio volumio[1898]: info: Restoring Previous Volume level: 70 false false Aug 19 15:18:25 volumio volumio[1898]: info: Volume configurations have been set Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 19 15:18:25 volumio volumio[1898]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; linear Vol Steps: 1 Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 19 15:18:25 volumio volumio[1898]: info: Disabling external Volume Control Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 15:18:25 volumio volumio[1898]: info: Getting Alsa Cards List without I2S DAC Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 19 15:18:25 volumio volumio[1898]: info: VolumeController:: Volume=70 Mute =false Aug 19 15:18:25 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:18:25 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:18:25 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:18:27 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:18:29 volumio volumio[1898]: info: VolumeController::SetAlsaVolume70 Aug 19 15:18:29 volumio volumio[1898]: info: CoreStateMachine::pushState Aug 19 15:18:29 volumio volumio[1898]: info: CorePlayQueue::getTrack 228 Aug 19 15:18:29 volumio volumio[1898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 15:18:29 volumio volumio[1898]: info: CoreCommandRouter::volumioPushState Aug 19 15:18:34 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:18:40 volumio kernel: hwmon hwmon1: Voltage normalised Aug 19 15:18:44 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 19 15:18:48 volumio volumio[1898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 15:18:48 volumio volumio[1898]: Error: connect ETIMEDOUT 173.236.212.42:443 Aug 19 15:18:48 volumio volumio[1898]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 19 15:18:48 volumio volumio[1898]: errno: -110, Aug 19 15:18:48 volumio volumio[1898]: code: 'ETIMEDOUT', Aug 19 15:18:48 volumio volumio[1898]: syscall: 'connect', Aug 19 15:18:48 volumio volumio[1898]: address: '173.236.212.42', Aug 19 15:18:48 volumio volumio[1898]: port: 443 Aug 19 15:18:48 volumio volumio[1898]: } Aug 19 15:18:48 volumio volumio[1898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 15:18:49 volumio sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 15:17 Aug 19 15:18:49 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"