-- Logs begin at Fri 2025-02-14 21:46:15 CST, end at Thu 2025-10-16 15:55:15 CST. -- Oct 16 15:54:00 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav in service mpd Oct 16 15:54:00 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_96k_PerfectTest.wav&metadata=false Oct 16 15:54:00 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:54:02 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 15:54:02 smsl-n100 volumio[1010]: info: CURURI: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Preloading song: music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2F03%20-%20%E5%A4%A9%E4%BD%BF.dff&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/256.dff in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2F256.dff&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/7.dff in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2F7.dff&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2Fo5dsd512.dff&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2FDSD64%202.8MHZ%E4%BD%95%E6%97%A5%E5%90%9B%E5%86%8D%E6%9D%A5.dsf&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=/SONY%20YEDS-18%20TEST%20CD/extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2F08.%201KHz%20Sine%20Wave%2C%200%20dB%2C%20L%2C%20emphasis%20off.dsf&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=/SONY%20YEDS-18%20TEST%20CD/extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2F11.%2010KHz%20Sine%20Wave%2C%200%20dB%2C%20R%2C%20emphasis%20off.dsf&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:02 smsl-n100 volumio[1010]: info: Exploding uri music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf in service mpd Oct 16 15:54:02 smsl-n100 volumio[1010]: info: ALBUMART /albumart?cacheid=236&web=%E5%BA%9E%E9%BE%99/%E7%BE%8E%E5%A5%BD%20%E5%AE%98%E6%96%B9%E6%AD%A3%E5%BC%8F%E7%89%88/extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FDSD%2FDSD128%205.6%20MHZ%E5%85%84%E5%BC%9F%E6%8A%B1%E4%B8%80%E4%B8%8B.dsf&metadata=false Oct 16 15:54:02 smsl-n100 volumio[1010]: info: URI /mnt/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::ClearQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 6 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::serviceStop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 6 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::serviceStop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::stop Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::clearPlayQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPlay Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::play index 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::play index undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Oct 16 15:54:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 121 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 31 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Oct 16 15:54:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand clear took 8 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf" Oct 16 15:54:04 smsl-n100 volumio[1010]: error: updateQueue error: null Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Oct 16 15:54:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ------------------------------ 26ms Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand add "USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf" took 21 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:54:04 smsl-n100 volumio[1010]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ------------------------------ 49ms Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Oct 16 15:54:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ------------------------------ 33ms Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand play took 13 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Oct 16 15:54:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand status took 11 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand status took 81 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 75 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":171,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DSD64 2.8MHZ何日君再来.dsf","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf","trackType":"dsf"} Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ------------------------------ 118ms Oct 16 15:54:04 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":171,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DSD64 2.8MHZ何日君再来.dsf","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf","trackType":"dsf"} Oct 16 15:54:04 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:54:04 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:04 smsl-n100 volumio[1010]: info: ------------------------------ 198ms Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:04 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:04 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::ClearQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:45 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:45 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::serviceStop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::serviceStop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ControllerMpd::stop Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::clearPlayQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/03 - 天使.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/256.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/7.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/o5dsd512.dff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/DSD64 2.8MHZ何日君再来.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPlay Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::play index 5 Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/11. 10KHz Sine Wave, 0 dB, R, emphasis off.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD/DSD128 5.6 MHZ兄弟抱一下.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::play index undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Oct 16 15:54:45 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 117 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 35 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Oct 16 15:54:45 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand clear took 7 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf" Oct 16 15:54:45 smsl-n100 volumio[1010]: error: updateQueue error: null Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Oct 16 15:54:45 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ------------------------------ 17ms Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand add "USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf" took 11 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:54:45 smsl-n100 volumio[1010]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ------------------------------ 35ms Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ------------------------------ 15ms Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:45 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:45 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:45 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:45 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:45 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:45 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Oct 16 15:54:45 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:45 smsl-n100 volumio[1010]: info: sendMpdCommand play took 63 milliseconds Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:45 smsl-n100 volumio[1010]: info: Oct 16 15:54:45 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:45 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:45 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:46 smsl-n100 volumio[1010]: info: sendMpdCommand status took 40 milliseconds Oct 16 15:54:46 smsl-n100 volumio[1010]: info: sendMpdCommand status took 36 milliseconds Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:46 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 16 15:54:46 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:46 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":60,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"08.1KHz Sine Wave, 0 dB, L, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf","trackType":"dsf"} Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:54:46 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":60,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"08.1KHz Sine Wave, 0 dB, L, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/DSD/08. 1KHz Sine Wave, 0 dB, L, emphasis off.dsf","trackType":"dsf"} Oct 16 15:54:46 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:54:46 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:46 smsl-n100 volumio[1010]: info: ------------------------------ 139ms Oct 16 15:54:46 smsl-n100 volumio[1010]: info: ------------------------------ 167ms Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:46 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:46 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:46 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:46 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:46 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:52 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 15:54:52 smsl-n100 volumio[1010]: info: CURURI: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:54:52 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::ClearQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::serviceStop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::serviceStop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::stop Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::clearPlayQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPlay Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::play index 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::addQueueItems Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::addQueueItems Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Preload queue cleared Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::stop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::saveQueue Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::play index undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::updateTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrackBlock Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Oct 16 15:54:53 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 126 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand stop took 36 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Oct 16 15:54:53 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand clear took 8 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" Oct 16 15:54:53 smsl-n100 volumio[1010]: error: updateQueue error: null Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Oct 16 15:54:53 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ------------------------------ 15ms Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" took 9 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:54:53 smsl-n100 volumio[1010]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ------------------------------ 34ms Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ------------------------------ 14ms Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand play took 7 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Oct 16 15:54:53 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:53 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:53 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Oct 16 15:54:53 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand status took 93 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand status took 52 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"176.4 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_176k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav","trackType":"wav"} Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ------------------------------ 170ms Oct 16 15:54:53 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":925,"duration":11,"samplerate":"176.4 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_176k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav","trackType":"wav"} Oct 16 15:54:53 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:54:53 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:53 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:53 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:53 smsl-n100 volumio[1010]: info: ------------------------------ 163ms Oct 16 15:54:54 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:54 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:54 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:54 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:54 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:54 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:54 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:54 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:54:54 smsl-n100 volumio[1010]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:54:54 smsl-n100 volumio[1010]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: Prefetching next song Oct 16 15:54:59 smsl-n100 volumio[1010]: info: DOING PREFETCH IN MPD Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" Oct 16 15:54:59 smsl-n100 volumio[1010]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" took 2 milliseconds Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: Oct 16 15:54:59 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:54:59 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:54:59 smsl-n100 volumio[1010]: info: Oct 16 15:54:59 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: options Oct 16 15:54:59 smsl-n100 volumio[1010]: info: sendMpdCommand consume 1 took 6 milliseconds Oct 16 15:54:59 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:54:59 smsl-n100 volumio[1010]: info: ------------------------------ 9ms Oct 16 15:54:59 smsl-n100 volumio[1010]: info: sendMpdCommand status took 4 milliseconds Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:54:59 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:54:59 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6125,"duration":11,"samplerate":"176.4 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_176k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav","trackType":"wav"} Oct 16 15:54:59 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 4 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:54:59 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:59 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 4 Oct 16 15:54:59 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:54:59 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:54:59 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:54:59 smsl-n100 volumio[1010]: info: ------------------------------ 83ms Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:03 smsl-n100 volumio[1010]: info: Oct 16 15:55:03 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:55:03 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:55:03 smsl-n100 volumio[1010]: info: Oct 16 15:55:03 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:55:03 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:55:03 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:55:03 smsl-n100 volumio[1010]: info: ------------------------------ 8ms Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:55:03 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:55:03 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:55:03 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:55:04 smsl-n100 volumio[1010]: info: sendMpdCommand status took 858 milliseconds Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: Oct 16 15:55:04 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:55:04 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:55:04 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_192k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav","trackType":"wav"} Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:55:04 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:55:04 smsl-n100 volumio[1010]: info: ------------------------------ 947ms Oct 16 15:55:04 smsl-n100 volumio[1010]: info: sendMpdCommand status took 77 milliseconds Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseState Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: ControllerMpd::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::servicePushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":763,"duration":11,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_192k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav","trackType":"wav"} Oct 16 15:55:04 smsl-n100 volumio[1010]: verbose: CURRENT POSITION 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState stateService play Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:55:04 smsl-n100 volumio[1010]: info: Received an update from plugin. extracting info from payload Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreStateMachine::pushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:04 smsl-n100 volumio[1010]: info: CoreCommandRouter::volumioPushState Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:55:04 smsl-n100 volumio[1010]: info: MRS: Pushing multiroomSync output Oct 16 15:55:04 smsl-n100 volumio[1010]: info: ------------------------------ 205ms Oct 16 15:55:09 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 5 Oct 16 15:55:09 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 6 Oct 16 15:55:09 smsl-n100 volumio[1010]: info: Prefetching next song Oct 16 15:55:09 smsl-n100 volumio[1010]: info: DOING PREFETCH IN MPD Oct 16 15:55:09 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav" Oct 16 15:55:09 smsl-n100 volumio[1010]: info: Oct 16 15:55:09 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:55:09 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:55:09 smsl-n100 volumio[1010]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav" took 5 milliseconds Oct 16 15:55:09 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:55:09 smsl-n100 volumio[1010]: info: ------------------------------ 7ms Oct 16 15:55:09 smsl-n100 volumio[1010]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 16 15:55:14 smsl-n100 volumio[1010]: info: Oct 16 15:55:14 smsl-n100 volumio[1010]: ---------------------------- MPD announces system playlist update Oct 16 15:55:14 smsl-n100 volumio[1010]: info: Ignoring MPD Status Update Oct 16 15:55:14 smsl-n100 volumio[1010]: info: Oct 16 15:55:14 smsl-n100 volumio[1010]: ---------------------------- MPD announces state update: player Oct 16 15:55:14 smsl-n100 volumio[1010]: info: ControllerMpd::getState Oct 16 15:55:14 smsl-n100 volumio[1010]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:55:14 smsl-n100 volumio[1010]: info: ------------------------------ 8ms Oct 16 15:55:14 smsl-n100 volumio[1010]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:55:14 smsl-n100 volumio[1010]: info: CorePlayQueue::getTrack 6 Oct 16 15:55:14 smsl-n100 volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:55:14 smsl-n100 volumio[1010]: Error: connect ETIMEDOUT 154.83.15.20:443 Oct 16 15:55:14 smsl-n100 volumio[1010]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 16 15:55:14 smsl-n100 volumio[1010]: errno: -110, Oct 16 15:55:14 smsl-n100 volumio[1010]: code: 'ETIMEDOUT', Oct 16 15:55:14 smsl-n100 volumio[1010]: syscall: 'connect', Oct 16 15:55:14 smsl-n100 volumio[1010]: address: '154.83.15.20', Oct 16 15:55:14 smsl-n100 volumio[1010]: port: 443 Oct 16 15:55:14 smsl-n100 volumio[1010]: } Oct 16 15:55:14 smsl-n100 volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:55:15 smsl-n100 sudo[3028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 15:54 Oct 16 15:55:15 smsl-n100 sudo[3028]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"