-- Logs begin at Sat 2026-05-23 20:27:16 CST, end at Wed 2026-05-27 15:39:03 CST. -- May 27 15:38:08 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::ClearQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::stop May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::stPlaybackTimer May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 6 May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::serviceStop May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 6 May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::serviceStop May 27 15:38:08 smsl-n100 volumio[1163]: info: ControllerMpd::stop May 27 15:38:08 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::clearPlayQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::addQueueItems May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::addQueueItems May 27 15:38:08 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/256.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/256.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/7.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/7.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD1分 1kHz.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD1分 1kHz.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD256.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD256.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD64天使.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD64天使.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPlay May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::play index 4 May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:08 smsl-n100 volumio[1163]: info: CoreStateMachine::addQueueItems May 27 15:38:08 smsl-n100 volumio[1163]: info: CorePlayQueue::addQueueItems May 27 15:38:08 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/o5dsd512.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/o5dsd512.dff May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD64 2.8MHZ何日君再来.dsf May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD64 2.8MHZ何日君再来.dsf May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf May 27 15:38:08 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf May 27 15:38:08 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD128 勇气.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD128 勇气.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::stop May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::play index undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::startPlaybackTimer May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::clearAddPlayTracks USB/hong/音乐/DSD/DSD64天使.dff May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand stop took 70 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand stop took 18 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand clear May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 16 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand clear took 14 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 11 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 12 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/DSD/DSD64天使.dff" May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService stop May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: No code May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService stop May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: No code May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 87ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 85ms May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: error: updateQueue error: null May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 96ms May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 86 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/DSD/DSD64天使.dff" took 86 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 16ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 14ms May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand play May 27 15:38:09 smsl-n100 volumio[1163]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 120ms May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:09 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 77ms May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand play took 70 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 67ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 63ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 19 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 17 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 12 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 9 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: May 27 15:38:09 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: MAXVOL, message: May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 38 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 37 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 37 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 36 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 30 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand status took 27 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":491,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:09 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:09 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:09 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 131ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 150ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 140ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 137ms May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 101 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 100 milliseconds May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":549,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:09 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":589,"duration":267,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"DSD64天使.dff","artist":null,"album":null,"uri":"USB/hong/音乐/DSD/DSD64天使.dff","trackType":"dff"} May 27 15:38:09 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:09 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 193ms May 27 15:38:09 smsl-n100 volumio[1163]: info: ------------------------------ 191ms May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:09 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:09 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: MAXVOL, message: May 27 15:38:09 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:09 smsl-n100 volumio[1163]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 27 15:38:14 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 15:38:14 smsl-n100 volumio[1163]: info: CURURI: music-library/USB/hong/音乐/PCM384 May 27 15:38:14 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:19 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::ClearQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::stPlaybackTimer May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::serviceStop May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 4 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::serviceStop May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::stop May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::clearPlayQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::addQueueItems May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::addQueueItems May 27 15:38:19 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(1) 44.1k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(1) 44.1k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(2) 48k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(2) 48k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(3) 88.2k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(3) 88.2k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(4) 96k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(4) 96k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(5) 176.4k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(5) 176.4k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(6) 192k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(6) 192k.WAV May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(8) 384k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(8) 384k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(9) 768k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(9) 768k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/16_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/16_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPlay May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::play index 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::addQueueItems May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::addQueueItems May 27 15:38:19 smsl-n100 volumio[1163]: info: Preload queue cleared May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/16_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/16_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/24_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/24_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/24_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/24_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_176k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_176k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_192k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_192k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_384k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_44k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_44k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_48k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_48k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_88k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_88k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/32_96k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/32_96k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/検品用.mp3 May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/検品用.mp3 May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/正相位.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/正相位.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/相位音频Absolute Sine.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/相位音频Absolute Sine.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/蔡依林 - 大艺术家.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/蔡依林 - 大艺术家.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/左右声道测试.mp3 May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/左右声道测试.mp3 May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(9) 705.6k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(9) 705.6k.wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/Eagles_-_01_-_Hotel_California.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/Eagles_-_01_-_Hotel_California.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/08_ツァラトゥストラはかく語りき.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/08_ツァラトゥストラはかく語りき.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/09_シンフォニア　(交響曲　二短調 悪魔の家 より).flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/09_シンフォニア　(交響曲　二短調 悪魔の家 より).flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/10_バレエ ロメオとジュリエット より　モンタギュー家とキャピュレット家.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/10_バレエ ロメオとジュリエット より　モンタギュー家とキャピュレット家.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/11_バレエ組曲『火の鳥』 1919年版 より V．カスチェイ王の魔の踊り.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/11_バレエ組曲『火の鳥』 1919年版 より V．カスチェイ王の魔の踊り.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/12_首の差で.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/12_首の差で.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/02. 1KHz Sine Wave, 0 dB, L & R, emphasis off (1).wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/02. 1KHz Sine Wave, 0 dB, L & R, emphasis off (1).wav May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/(7) 352.8k.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/(7) 352.8k.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Adding Item to queue: music-library/USB/hong/音乐/PCM384/01. Bad Romance.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: Using cached record of: music-library/USB/hong/音乐/PCM384/01. Bad Romance.flac May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::saveQueue May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::play index undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::updateTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrackBlock May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::startPlaybackTimer May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::clearAddPlayTracks USB/hong/音乐/PCM384/16_352k_PerfectTest.wav May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand stop took 111 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand stop took 27 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand clear May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 21 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand clear took 19 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 15 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 11 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/PCM384/16_352k_PerfectTest.wav" May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: No code May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: No code May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 82ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 81ms May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: error: updateQueue error: null May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 88ms May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 82 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/PCM384/16_352k_PerfectTest.wav" took 82 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 16ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 14ms May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand play May 27 15:38:19 smsl-n100 volumio[1163]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 117ms May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:19 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:19 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: MAXVOL, message: May 27 15:38:19 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:19 smsl-n100 volumio[1163]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 113ms May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand play took 106 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 105ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 103ms May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: May 27 15:38:19 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 25 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 24 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 20 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand status took 17 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 24 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 23 milliseconds May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:19 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":12,"samplerate":"352.8 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus stop May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":12,"samplerate":"352.8 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:19 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":12,"samplerate":"352.8 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:19 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":12,"samplerate":"352.8 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:19 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:19 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:19 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:19 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 130ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 148ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 138ms May 27 15:38:19 smsl-n100 volumio[1163]: info: ------------------------------ 135ms May 27 15:38:20 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:20 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:20 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:20 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:20 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:20 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: MAXVOL, message: May 27 15:38:20 smsl-n100 volumio[1163]: error: Serial API: Failed to decode command: 100, message: undefined May 27 15:38:20 smsl-n100 volumio[1163]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 27 15:38:26 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 8 May 27 15:38:26 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:26 smsl-n100 volumio[1163]: info: Prefetching next song May 27 15:38:26 smsl-n100 volumio[1163]: info: DOING PREFETCH IN MPD May 27 15:38:26 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/PCM384/16_384k_PerfectTest.wav" May 27 15:38:26 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/PCM384/16_384k_PerfectTest.wav" took 48 milliseconds May 27 15:38:26 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand consume 1 May 27 15:38:26 smsl-n100 volumio[1163]: info: May 27 15:38:26 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:26 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:26 smsl-n100 volumio[1163]: info: sendMpdCommand consume 1 took 5 milliseconds May 27 15:38:26 smsl-n100 volumio[1163]: info: May 27 15:38:26 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:26 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:26 smsl-n100 volumio[1163]: info: May 27 15:38:26 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:26 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:26 smsl-n100 volumio[1163]: info: ------------------------------ 14ms May 27 15:38:26 smsl-n100 volumio[1163]: info: ------------------------------ 10ms May 27 15:38:26 smsl-n100 volumio[1163]: info: ------------------------------ 8ms May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:30 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:30 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:30 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:30 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:30 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:30 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:30 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:30 smsl-n100 volumio[1163]: info: May 27 15:38:30 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:30 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:30 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:30 smsl-n100 volumio[1163]: info: ------------------------------ 18ms May 27 15:38:30 smsl-n100 volumio[1163]: info: CoreStateMachine::startPlaybackTimer May 27 15:38:30 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1001 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: info: May 27 15:38:31 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 1014ms May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1012 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 1010ms May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1007 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 13 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 1063ms May 27 15:38:31 smsl-n100 volumio[1163]: info: May 27 15:38:31 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:31 smsl-n100 volumio[1163]: info: May 27 15:38:31 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 62 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 56 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 56 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 14 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand status took 11 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":170,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 1155ms May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 1152ms May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 94 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 90 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 88 milliseconds May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":872,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":872,"duration":11,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:31 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:31 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:31 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:31 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 243ms May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 192ms May 27 15:38:31 smsl-n100 volumio[1163]: info: ------------------------------ 189ms May 27 15:38:36 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 9 May 27 15:38:36 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:36 smsl-n100 volumio[1163]: info: Prefetching next song May 27 15:38:36 smsl-n100 volumio[1163]: info: DOING PREFETCH IN MPD May 27 15:38:36 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/PCM384/24_352k_PerfectTest.wav" May 27 15:38:36 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/PCM384/24_352k_PerfectTest.wav" took 8 milliseconds May 27 15:38:36 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand consume 1 May 27 15:38:36 smsl-n100 volumio[1163]: info: May 27 15:38:36 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:36 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:36 smsl-n100 volumio[1163]: info: May 27 15:38:36 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:36 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:36 smsl-n100 volumio[1163]: info: May 27 15:38:36 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:36 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:36 smsl-n100 volumio[1163]: info: sendMpdCommand consume 1 took 14 milliseconds May 27 15:38:36 smsl-n100 volumio[1163]: info: ------------------------------ 11ms May 27 15:38:36 smsl-n100 volumio[1163]: info: ------------------------------ 8ms May 27 15:38:36 smsl-n100 volumio[1163]: info: ------------------------------ 7ms May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:41 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:41 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:41 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:41 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:41 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:41 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:41 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:41 smsl-n100 volumio[1163]: info: May 27 15:38:41 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:41 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:41 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:41 smsl-n100 volumio[1163]: info: ------------------------------ 19ms May 27 15:38:41 smsl-n100 volumio[1163]: info: CoreStateMachine::startPlaybackTimer May 27 15:38:41 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:41 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:41 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:41 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:41 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1004 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: info: May 27 15:38:42 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 1019ms May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1016 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 1015ms May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 1013 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: info: May 27 15:38:42 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:42 smsl-n100 volumio[1163]: info: May 27 15:38:42 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 29 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 25 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 18 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 13 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand status took 10 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":207,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":282,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 1160ms May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 1156ms May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 1153ms May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 110 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 108 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 99 milliseconds May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":424,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":424,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":424,"duration":10,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} May 27 15:38:42 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:42 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:42 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:42 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 215ms May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 201ms May 27 15:38:42 smsl-n100 volumio[1163]: info: ------------------------------ 198ms May 27 15:38:47 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 10 May 27 15:38:47 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:47 smsl-n100 volumio[1163]: info: Prefetching next song May 27 15:38:47 smsl-n100 volumio[1163]: info: DOING PREFETCH IN MPD May 27 15:38:47 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/PCM384/24_384k_PerfectTest.wav" May 27 15:38:47 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/PCM384/24_384k_PerfectTest.wav" took 3 milliseconds May 27 15:38:47 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand consume 1 May 27 15:38:47 smsl-n100 volumio[1163]: info: May 27 15:38:47 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:47 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:47 smsl-n100 volumio[1163]: info: sendMpdCommand consume 1 took 3 milliseconds May 27 15:38:47 smsl-n100 volumio[1163]: info: May 27 15:38:47 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:47 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:47 smsl-n100 volumio[1163]: info: May 27 15:38:47 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:47 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:47 smsl-n100 volumio[1163]: info: ------------------------------ 11ms May 27 15:38:47 smsl-n100 volumio[1163]: info: ------------------------------ 8ms May 27 15:38:47 smsl-n100 volumio[1163]: info: ------------------------------ 5ms May 27 15:38:51 smsl-n100 volumio[1163]: info: CoreStateMachine::startPlaybackTimer May 27 15:38:51 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:52 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:52 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:52 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:52 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:52 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:52 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:52 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:52 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:52 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:52 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:52 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:52 smsl-n100 volumio[1163]: info: May 27 15:38:52 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:52 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:52 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:52 smsl-n100 volumio[1163]: info: ------------------------------ 22ms May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 683 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: info: May 27 15:38:53 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 692ms May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 689 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 688ms May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 681 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 734ms May 27 15:38:53 smsl-n100 volumio[1163]: info: May 27 15:38:53 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:53 smsl-n100 volumio[1163]: info: May 27 15:38:53 smsl-n100 volumio[1163]: ---------------------------- MPD announces state update: player May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::getState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand status May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 59 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 54 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 54 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 18 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand status took 12 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseState May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 840ms May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 836ms May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 113 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 110 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: info: sendMpdCommand playlistinfo took 109 milliseconds May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: verbose: ControllerMpd::parseTrackInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":423,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":423,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ControllerMpd::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::servicePushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: verbose: STATE SERVICE {"status":"play","position":0,"seek":595,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/hong/音乐/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} May 27 15:38:53 smsl-n100 volumio[1163]: verbose: CURRENT POSITION 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState stateService play May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::syncState currentStatus play May 27 15:38:53 smsl-n100 volumio[1163]: info: Received an update from plugin. extracting info from payload May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreStateMachine::pushState May 27 15:38:53 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:53 smsl-n100 volumio[1163]: info: CoreCommandRouter::volumioPushState May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 256ms May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 214ms May 27 15:38:53 smsl-n100 volumio[1163]: info: ------------------------------ 210ms May 27 15:38:58 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 11 May 27 15:38:58 smsl-n100 volumio[1163]: info: CorePlayQueue::getTrack 12 May 27 15:38:58 smsl-n100 volumio[1163]: info: Prefetching next song May 27 15:38:58 smsl-n100 volumio[1163]: info: DOING PREFETCH IN MPD May 27 15:38:58 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand add "USB/hong/音乐/PCM384/32_176k_PerfectTest.wav" May 27 15:38:58 smsl-n100 volumio[1163]: info: sendMpdCommand add "USB/hong/音乐/PCM384/32_176k_PerfectTest.wav" took 3 milliseconds May 27 15:38:58 smsl-n100 volumio[1163]: verbose: ControllerMpd::sendMpdCommand consume 1 May 27 15:38:58 smsl-n100 volumio[1163]: info: May 27 15:38:58 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:58 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:58 smsl-n100 volumio[1163]: info: May 27 15:38:58 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:58 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:58 smsl-n100 volumio[1163]: info: May 27 15:38:58 smsl-n100 volumio[1163]: ---------------------------- MPD announces system playlist update May 27 15:38:58 smsl-n100 volumio[1163]: info: Ignoring MPD Status Update May 27 15:38:58 smsl-n100 volumio[1163]: info: sendMpdCommand consume 1 took 12 milliseconds May 27 15:38:58 smsl-n100 volumio[1163]: info: ------------------------------ 10ms May 27 15:38:58 smsl-n100 volumio[1163]: info: ------------------------------ 8ms May 27 15:38:58 smsl-n100 volumio[1163]: info: ------------------------------ 7ms May 27 15:39:02 smsl-n100 volumio[1163]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:39:02 smsl-n100 volumio[1163]: Error: connect ETIMEDOUT 108.160.169.179:443 May 27 15:39:02 smsl-n100 volumio[1163]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 27 15:39:02 smsl-n100 volumio[1163]: errno: -110, May 27 15:39:02 smsl-n100 volumio[1163]: code: 'ETIMEDOUT', May 27 15:39:02 smsl-n100 volumio[1163]: syscall: 'connect', May 27 15:39:02 smsl-n100 volumio[1163]: address: '108.160.169.179', May 27 15:39:02 smsl-n100 volumio[1163]: port: 443 May 27 15:39:02 smsl-n100 volumio[1163]: } May 27 15:39:02 smsl-n100 volumio[1163]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:39:03 smsl-n100 sudo[5370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 15:38 May 27 15:39:03 smsl-n100 sudo[5370]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"