-- Logs begin at Fri 2025-02-14 21:46:15 CST, end at Thu 2025-10-16 15:09:58 CST. -- Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:00 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::play index 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:00 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:08:00 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:08:00 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:00 smsl-n100 volumio[990]: info: Oct 16 15:08:00 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand stop took 226 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand stop took 58 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:00 smsl-n100 volumio[990]: info: Oct 16 15:08:00 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:00 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand status took 18 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand clear took 16 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/検品用.mp3" Oct 16 15:08:00 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:00 smsl-n100 volumio[990]: info: Oct 16 15:08:00 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:00 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:00 smsl-n100 volumio[990]: info: ------------------------------ 22ms Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/検品用.mp3" took 16 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:00 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: ------------------------------ 51ms Oct 16 15:08:00 smsl-n100 volumio[990]: info: Oct 16 15:08:00 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:00 smsl-n100 volumio[990]: info: ------------------------------ 27ms Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand play took 19 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:00 smsl-n100 volumio[990]: info: Oct 16 15:08:00 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand status took 81 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand status took 40 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":45,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LRC","artist":"Takeshi Susumu","album":null,"uri":"USB/6436-3238/测试音频/検品用.mp3","trackType":"mp3"} Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:00 smsl-n100 volumio[990]: info: ------------------------------ 145ms Oct 16 15:08:00 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":45,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LRC","artist":"Takeshi Susumu","album":null,"uri":"USB/6436-3238/测试音频/検品用.mp3","trackType":"mp3"} Oct 16 15:08:00 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:00 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:00 smsl-n100 volumio[990]: info: ------------------------------ 206ms Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:00 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:00 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:00 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:00 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:05 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 15:08:05 smsl-n100 volumio[990]: info: CURURI: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Preloading song: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav in service mpd Oct 16 15:08:05 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F16_352k_PerfectTest.wav&metadata=false Oct 16 15:08:05 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav in service mpd Oct 16 15:08:05 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F16_384k_PerfectTest.wav&metadata=false Oct 16 15:08:05 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav in service mpd Oct 16 15:08:05 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F24_352k_PerfectTest.wav&metadata=false Oct 16 15:08:05 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav in service mpd Oct 16 15:08:05 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F24_384k_PerfectTest.wav&metadata=false Oct 16 15:08:05 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:05 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav in service mpd Oct 16 15:08:05 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_176k_PerfectTest.wav&metadata=false Oct 16 15:08:05 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_192k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_352k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_384k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_44k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_48k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&web=//extralarge&path=%2Fmnt%2FUSB%2F6436-3238%2F%E6%B5%8B%E8%AF%95%E9%9F%B3%E9%A2%91%2FPCM384%2F32_88k_PerfectTest.wav&metadata=false Oct 16 15:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:06 smsl-n100 volumio[990]: info: Exploding uri music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav in service mpd Oct 16 15:08:06 smsl-n100 volumio[990]: info: ALBUMART /albumart?cacheid=288&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:08:06 smsl-n100 volumio[990]: info: URI /mnt/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:10 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:10 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:10 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:10 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:10 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:11 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::play index 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:11 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:11 smsl-n100 volumio[990]: info: Oct 16 15:08:11 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand stop took 152 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand stop took 40 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:11 smsl-n100 volumio[990]: info: Oct 16 15:08:11 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:11 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand status took 13 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand clear took 11 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav" Oct 16 15:08:11 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:11 smsl-n100 volumio[990]: info: Oct 16 15:08:11 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:11 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:11 smsl-n100 volumio[990]: info: ------------------------------ 21ms Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav" took 15 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:11 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: ------------------------------ 45ms Oct 16 15:08:11 smsl-n100 volumio[990]: info: ------------------------------ 24ms Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand play took 17 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: info: Oct 16 15:08:11 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:11 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:11 smsl-n100 volumio[990]: info: Oct 16 15:08:11 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:11 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand status took 82 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand status took 32 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":14,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_44k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: CURRENT POSITION 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:11 smsl-n100 volumio[990]: info: ------------------------------ 132ms Oct 16 15:08:11 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":14,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_44k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:11 smsl-n100 volumio[990]: verbose: CURRENT POSITION 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:11 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:11 smsl-n100 volumio[990]: info: ------------------------------ 156ms Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:11 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:11 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:11 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:11 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:14 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:14 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:14 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 8 Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:14 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:14 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:14 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::play index 4 Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:14 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:14 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:14 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:14 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:14 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:14 smsl-n100 volumio[990]: info: Oct 16 15:08:14 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:14 smsl-n100 volumio[990]: info: sendMpdCommand stop took 147 milliseconds Oct 16 15:08:14 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:14 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:14 smsl-n100 volumio[990]: info: sendMpdCommand stop took 33 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:15 smsl-n100 volumio[990]: info: Oct 16 15:08:15 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:15 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand clear took 7 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" Oct 16 15:08:15 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:15 smsl-n100 volumio[990]: info: Oct 16 15:08:15 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:15 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:15 smsl-n100 volumio[990]: info: ------------------------------ 25ms Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" took 20 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:15 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:15 smsl-n100 volumio[990]: info: ------------------------------ 46ms Oct 16 15:08:15 smsl-n100 volumio[990]: info: Oct 16 15:08:15 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:15 smsl-n100 volumio[990]: info: ------------------------------ 54ms Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand play took 47 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:15 smsl-n100 volumio[990]: info: Oct 16 15:08:15 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:15 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:15 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand status took 90 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 85 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: 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:08:15 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:15 smsl-n100 volumio[990]: info: ------------------------------ 127ms Oct 16 15:08:15 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 16 15:08:15 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: 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:08:15 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:15 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:15 smsl-n100 volumio[990]: info: ------------------------------ 227ms Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:15 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:15 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:15 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:15 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:19 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:19 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::play index 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:19 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:19 smsl-n100 volumio[990]: info: Oct 16 15:08:19 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand stop took 156 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand stop took 43 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:19 smsl-n100 volumio[990]: info: Oct 16 15:08:19 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:19 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand status took 12 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand clear took 10 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" Oct 16 15:08:19 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:19 smsl-n100 volumio[990]: info: Oct 16 15:08:19 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:19 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:19 smsl-n100 volumio[990]: info: ------------------------------ 25ms Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" took 18 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:19 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: ------------------------------ 48ms Oct 16 15:08:19 smsl-n100 volumio[990]: info: Oct 16 15:08:19 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:19 smsl-n100 volumio[990]: info: ------------------------------ 28ms Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand play took 22 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:19 smsl-n100 volumio[990]: info: Oct 16 15:08:19 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:19 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:19 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:19 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:19 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand status took 102 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: 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:08:19 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:19 smsl-n100 volumio[990]: info: ------------------------------ 139ms Oct 16 15:08:19 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 16 15:08:19 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: 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:08:19 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:19 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:19 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:19 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:19 smsl-n100 volumio[990]: info: ------------------------------ 259ms Oct 16 15:08:20 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:20 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:20 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:20 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:20 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:20 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:20 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:20 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:20 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:24 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:24 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::play index 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:24 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:24 smsl-n100 volumio[990]: info: Oct 16 15:08:24 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand stop took 146 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand stop took 39 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:24 smsl-n100 volumio[990]: info: Oct 16 15:08:24 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:24 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand clear took 7 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav" Oct 16 15:08:24 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:24 smsl-n100 volumio[990]: info: Oct 16 15:08:24 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:24 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:24 smsl-n100 volumio[990]: info: ------------------------------ 21ms Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav" took 16 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:24 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: ------------------------------ 44ms Oct 16 15:08:24 smsl-n100 volumio[990]: info: Oct 16 15:08:24 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:24 smsl-n100 volumio[990]: info: ------------------------------ 53ms Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand play took 44 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:24 smsl-n100 volumio[990]: info: Oct 16 15:08:24 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:24 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:24 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:24 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:24 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand status took 75 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 70 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":13,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_48k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: CURRENT POSITION 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:24 smsl-n100 volumio[990]: info: ------------------------------ 123ms Oct 16 15:08:24 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":13,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_48k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:24 smsl-n100 volumio[990]: verbose: CURRENT POSITION 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:24 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:24 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:24 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:25 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:25 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:25 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:25 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:25 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:25 smsl-n100 volumio[990]: info: ------------------------------ 209ms Oct 16 15:08:25 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:25 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:25 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:25 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:25 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:25 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:25 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:25 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:25 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:25 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:27 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:27 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:27 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 9 Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:27 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:27 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:27 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::play index 10 Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:27 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:27 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:27 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:27 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:28 smsl-n100 volumio[990]: info: Oct 16 15:08:28 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand stop took 127 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand stop took 34 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:28 smsl-n100 volumio[990]: info: Oct 16 15:08:28 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:28 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand status took 15 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand clear took 13 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav" Oct 16 15:08:28 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:28 smsl-n100 volumio[990]: info: Oct 16 15:08:28 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:28 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:28 smsl-n100 volumio[990]: info: ------------------------------ 14ms Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav" took 9 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:28 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: ------------------------------ 40ms Oct 16 15:08:28 smsl-n100 volumio[990]: info: ------------------------------ 14ms Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand play took 12 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: info: Oct 16 15:08:28 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:28 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:28 smsl-n100 volumio[990]: info: Oct 16 15:08:28 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:28 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand status took 16 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand status took 65 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 59 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_88k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: CURRENT POSITION 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:28 smsl-n100 volumio[990]: info: ------------------------------ 97ms Oct 16 15:08:28 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":371,"duration":12,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_88k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:28 smsl-n100 volumio[990]: verbose: CURRENT POSITION 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:28 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:28 smsl-n100 volumio[990]: info: ------------------------------ 193ms Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:28 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:28 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:28 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:28 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:31 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 10 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:31 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::play index 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:31 smsl-n100 volumio[990]: info: Oct 16 15:08:31 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand stop took 146 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand stop took 38 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:31 smsl-n100 volumio[990]: info: Oct 16 15:08:31 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:31 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand clear took 7 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav" Oct 16 15:08:31 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:31 smsl-n100 volumio[990]: info: Oct 16 15:08:31 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:31 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:31 smsl-n100 volumio[990]: info: ------------------------------ 25ms Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav" took 20 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:31 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: ------------------------------ 44ms Oct 16 15:08:31 smsl-n100 volumio[990]: info: ------------------------------ 13ms Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand play took 6 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:31 smsl-n100 volumio[990]: info: Oct 16 15:08:31 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:31 smsl-n100 volumio[990]: info: Oct 16 15:08:31 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand status took 49 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":11,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_96k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: CURRENT POSITION 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:31 smsl-n100 volumio[990]: info: ------------------------------ 79ms Oct 16 15:08:31 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":11,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_96k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:31 smsl-n100 volumio[990]: verbose: CURRENT POSITION 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:31 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:31 smsl-n100 volumio[990]: info: ------------------------------ 159ms Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:31 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:31 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:34 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 11 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:34 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::play index 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:34 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:34 smsl-n100 volumio[990]: info: Oct 16 15:08:34 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand stop took 167 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand stop took 36 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:34 smsl-n100 volumio[990]: info: Oct 16 15:08:34 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:34 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand status took 38 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand clear took 37 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" Oct 16 15:08:34 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:34 smsl-n100 volumio[990]: info: Oct 16 15:08:34 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:34 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:34 smsl-n100 volumio[990]: info: ------------------------------ 16ms Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" took 11 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:34 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: ------------------------------ 63ms Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:34 smsl-n100 volumio[990]: info: Oct 16 15:08:34 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:34 smsl-n100 volumio[990]: info: ------------------------------ 73ms Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand play took 67 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:34 smsl-n100 volumio[990]: info: Oct 16 15:08:34 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand status took 22 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"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:08:34 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:34 smsl-n100 volumio[990]: info: ------------------------------ 44ms Oct 16 15:08:34 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"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:08:34 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:34 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:34 smsl-n100 volumio[990]: info: ------------------------------ 104ms Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:34 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:34 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:34 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:34 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:37 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::ClearQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::serviceStop Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::serviceStop Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::stop Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::clearPlayQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:37 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPlay Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::play index 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::addQueueItems Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::addQueueItems Oct 16 15:08:37 smsl-n100 volumio[990]: info: Preload queue cleared Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_44k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_48k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_88k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384/32_96k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::stop Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::saveQueue Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::play index undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::updateTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrackBlock Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:08:37 smsl-n100 volumio[990]: info: Oct 16 15:08:37 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand stop took 118 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand stop took 36 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:08:37 smsl-n100 volumio[990]: info: Oct 16 15:08:37 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:37 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand status took 14 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand clear took 12 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav" Oct 16 15:08:37 smsl-n100 volumio[990]: error: updateQueue error: null Oct 16 15:08:37 smsl-n100 volumio[990]: info: Oct 16 15:08:37 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:37 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:37 smsl-n100 volumio[990]: info: ------------------------------ 20ms Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav" took 16 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:08:37 smsl-n100 volumio[990]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: ------------------------------ 43ms Oct 16 15:08:37 smsl-n100 volumio[990]: info: ------------------------------ 13ms Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand play took 8 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:37 smsl-n100 volumio[990]: info: Oct 16 15:08:37 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:37 smsl-n100 volumio[990]: info: Oct 16 15:08:37 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand status took 33 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand status took 26 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"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/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: CURRENT POSITION 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:08:37 smsl-n100 volumio[990]: info: ------------------------------ 75ms Oct 16 15:08:37 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14,"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/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:37 smsl-n100 volumio[990]: verbose: CURRENT POSITION 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:37 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:37 smsl-n100 volumio[990]: info: ------------------------------ 142ms Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:37 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:37 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:08:37 smsl-n100 volumio[990]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:08:37 smsl-n100 volumio[990]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:08:44 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:44 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:44 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:08:44 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav" Oct 16 15:08:44 smsl-n100 volumio[990]: info: Oct 16 15:08:44 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:44 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:44 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav" took 7 milliseconds Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:08:44 smsl-n100 volumio[990]: info: Oct 16 15:08:44 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: options Oct 16 15:08:44 smsl-n100 volumio[990]: info: ------------------------------ 11ms Oct 16 15:08:44 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 6 milliseconds Oct 16 15:08:44 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:44 smsl-n100 volumio[990]: info: sendMpdCommand status took 3 milliseconds Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:44 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:44 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7893,"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/6436-3238/测试音频/PCM384/16_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:44 smsl-n100 volumio[990]: verbose: CURRENT POSITION 0 Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:44 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:44 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 0 Oct 16 15:08:44 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:44 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:44 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:44 smsl-n100 volumio[990]: info: ------------------------------ 81ms Oct 16 15:08:47 smsl-n100 volumio[990]: info: Oct 16 15:08:47 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:47 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:47 smsl-n100 volumio[990]: info: Oct 16 15:08:47 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:47 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:47 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:47 smsl-n100 volumio[990]: info: ------------------------------ 8ms Oct 16 15:08:48 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:48 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:48 smsl-n100 volumio[990]: info: sendMpdCommand status took 1134 milliseconds Oct 16 15:08:48 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:48 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:48 smsl-n100 volumio[990]: info: Oct 16 15:08:48 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:48 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:48 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:49 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:49 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: 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/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: CURRENT POSITION 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:49 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:49 smsl-n100 volumio[990]: info: ------------------------------ 1217ms Oct 16 15:08:49 smsl-n100 volumio[990]: info: sendMpdCommand status took 74 milliseconds Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:49 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:49 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: 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/6436-3238/测试音频/PCM384/16_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:49 smsl-n100 volumio[990]: verbose: CURRENT POSITION 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:49 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:49 smsl-n100 volumio[990]: info: ------------------------------ 179ms Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:49 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:49 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:55 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 1 Oct 16 15:08:55 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:55 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:08:55 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:08:55 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav" Oct 16 15:08:55 smsl-n100 volumio[990]: info: Oct 16 15:08:55 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:55 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:55 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav" took 5 milliseconds Oct 16 15:08:55 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:08:55 smsl-n100 volumio[990]: info: ------------------------------ 8ms Oct 16 15:08:55 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 16 15:08:58 smsl-n100 volumio[990]: info: Oct 16 15:08:58 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:08:58 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:08:58 smsl-n100 volumio[990]: info: Oct 16 15:08:58 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:58 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:58 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:58 smsl-n100 volumio[990]: info: ------------------------------ 8ms Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: Oct 16 15:08:59 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:08:59 smsl-n100 volumio[990]: info: sendMpdCommand status took 1154 milliseconds Oct 16 15:08:59 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: sendMpdCommand status took 23 milliseconds Oct 16 15:08:59 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: 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/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: CURRENT POSITION 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:59 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:59 smsl-n100 volumio[990]: info: ------------------------------ 1282ms Oct 16 15:08:59 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":121,"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/6436-3238/测试音频/PCM384/24_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:08:59 smsl-n100 volumio[990]: verbose: CURRENT POSITION 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:08:59 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:08:59 smsl-n100 volumio[990]: info: ------------------------------ 239ms Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:08:59 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:08:59 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:04 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 2 Oct 16 15:09:04 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:04 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:09:04 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:09:04 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav" Oct 16 15:09:04 smsl-n100 volumio[990]: info: Oct 16 15:09:04 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:04 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:04 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav" took 6 milliseconds Oct 16 15:09:04 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:09:04 smsl-n100 volumio[990]: info: ------------------------------ 9ms Oct 16 15:09:04 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 16 15:09:08 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:09:08 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:09 smsl-n100 volumio[990]: info: Oct 16 15:09:09 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:09 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:09 smsl-n100 volumio[990]: info: Oct 16 15:09:09 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:09 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:09 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:09 smsl-n100 volumio[990]: info: ------------------------------ 9ms Oct 16 15:09:09 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:09 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:09 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:09 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:09 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:09 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:10 smsl-n100 volumio[990]: info: Oct 16 15:09:10 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:10 smsl-n100 volumio[990]: info: sendMpdCommand status took 764 milliseconds Oct 16 15:09:10 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:10 smsl-n100 volumio[990]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:09:10 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:10 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: 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/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: CURRENT POSITION 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:10 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:10 smsl-n100 volumio[990]: info: ------------------------------ 838ms Oct 16 15:09:10 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 76 milliseconds Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:10 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"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/6436-3238/测试音频/PCM384/24_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:10 smsl-n100 volumio[990]: verbose: CURRENT POSITION 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:10 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:10 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:10 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:10 smsl-n100 volumio[990]: info: ------------------------------ 153ms Oct 16 15:09:16 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 3 Oct 16 15:09:16 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:16 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:09:16 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:09:16 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" Oct 16 15:09:16 smsl-n100 volumio[990]: info: Oct 16 15:09:16 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:16 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:16 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_176k_PerfectTest.wav" took 5 milliseconds Oct 16 15:09:16 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:09:16 smsl-n100 volumio[990]: info: ------------------------------ 7ms Oct 16 15:09:16 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 16 15:09:20 smsl-n100 volumio[990]: info: Oct 16 15:09:20 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:20 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:20 smsl-n100 volumio[990]: info: Oct 16 15:09:20 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:20 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:20 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:20 smsl-n100 volumio[990]: info: ------------------------------ 12ms Oct 16 15:09:20 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:09:20 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: Oct 16 15:09:21 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:21 smsl-n100 volumio[990]: info: sendMpdCommand status took 776 milliseconds Oct 16 15:09:21 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: sendMpdCommand status took 5 milliseconds Oct 16 15:09:21 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: 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:09:21 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:21 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:21 smsl-n100 volumio[990]: info: ------------------------------ 849ms Oct 16 15:09:21 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":740,"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:09:21 smsl-n100 volumio[990]: verbose: CURRENT POSITION 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:21 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:21 smsl-n100 volumio[990]: info: ------------------------------ 134ms Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:21 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:21 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:26 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 4 Oct 16 15:09:26 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:26 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:09:26 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:09:26 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" Oct 16 15:09:26 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_192k_PerfectTest.wav" took 3 milliseconds Oct 16 15:09:26 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:09:26 smsl-n100 volumio[990]: info: Oct 16 15:09:26 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:26 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:26 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 16 15:09:26 smsl-n100 volumio[990]: info: ------------------------------ 5ms Oct 16 15:09:30 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:09:30 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:30 smsl-n100 volumio[990]: info: Oct 16 15:09:30 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:30 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:30 smsl-n100 volumio[990]: info: Oct 16 15:09:30 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:30 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:30 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:30 smsl-n100 volumio[990]: info: ------------------------------ 7ms Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:31 smsl-n100 volumio[990]: info: Oct 16 15:09:31 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:31 smsl-n100 volumio[990]: info: sendMpdCommand status took 859 milliseconds Oct 16 15:09:31 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:09:31 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: 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:09:31 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:31 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:31 smsl-n100 volumio[990]: info: ------------------------------ 935ms Oct 16 15:09:31 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":727,"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:09:31 smsl-n100 volumio[990]: verbose: CURRENT POSITION 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:31 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:31 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:31 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:31 smsl-n100 volumio[990]: info: ------------------------------ 143ms Oct 16 15:09:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 5 Oct 16 15:09:37 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:37 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:09:37 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:09:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav" Oct 16 15:09:37 smsl-n100 volumio[990]: info: Oct 16 15:09:37 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:37 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:37 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav" took 5 milliseconds Oct 16 15:09:37 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:09:37 smsl-n100 volumio[990]: info: ------------------------------ 6ms Oct 16 15:09:37 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 16 15:09:41 smsl-n100 volumio[990]: info: Oct 16 15:09:41 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:41 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:41 smsl-n100 volumio[990]: info: Oct 16 15:09:41 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:41 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:41 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:41 smsl-n100 volumio[990]: info: ------------------------------ 8ms Oct 16 15:09:41 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:09:41 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:42 smsl-n100 volumio[990]: info: Oct 16 15:09:42 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:42 smsl-n100 volumio[990]: info: sendMpdCommand status took 771 milliseconds Oct 16 15:09:42 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:09:42 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: CURRENT POSITION 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:42 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:42 smsl-n100 volumio[990]: info: ------------------------------ 847ms Oct 16 15:09:42 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 64 milliseconds Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1,"duration":11,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_352k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_352k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:42 smsl-n100 volumio[990]: verbose: CURRENT POSITION 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:42 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:42 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:42 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:42 smsl-n100 volumio[990]: info: ------------------------------ 145ms Oct 16 15:09:48 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 6 Oct 16 15:09:48 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:48 smsl-n100 volumio[990]: info: Prefetching next song Oct 16 15:09:48 smsl-n100 volumio[990]: info: DOING PREFETCH IN MPD Oct 16 15:09:48 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav" Oct 16 15:09:48 smsl-n100 volumio[990]: info: Oct 16 15:09:48 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:48 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:48 smsl-n100 volumio[990]: info: sendMpdCommand add "USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav" took 6 milliseconds Oct 16 15:09:48 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:09:48 smsl-n100 volumio[990]: info: ------------------------------ 7ms Oct 16 15:09:48 smsl-n100 volumio[990]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 16 15:09:52 smsl-n100 volumio[990]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:09:52 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:52 smsl-n100 volumio[990]: info: Oct 16 15:09:52 smsl-n100 volumio[990]: ---------------------------- MPD announces system playlist update Oct 16 15:09:52 smsl-n100 volumio[990]: info: Ignoring MPD Status Update Oct 16 15:09:52 smsl-n100 volumio[990]: info: Oct 16 15:09:52 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:52 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:52 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:52 smsl-n100 volumio[990]: info: Oct 16 15:09:52 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:52 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:52 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:52 smsl-n100 volumio[990]: info: ------------------------------ 24ms Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: Oct 16 15:09:53 smsl-n100 volumio[990]: ---------------------------- MPD announces state update: player Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand status took 800 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: info: ControllerMpd::getState Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand status took 803 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand status took 95 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 94 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 97 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseState Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: 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":"32_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: CURRENT POSITION 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:53 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: CURRENT POSITION 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:53 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: ------------------------------ 1083ms Oct 16 15:09:53 smsl-n100 volumio[990]: info: ------------------------------ 1067ms Oct 16 15:09:53 smsl-n100 volumio[990]: info: sendMpdCommand playlistinfo took 157 milliseconds Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: ControllerMpd::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::servicePushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: STATE SERVICE {"status":"play","position":0,"seek":906,"duration":11,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32_384k_PerfectTest.wav","artist":null,"album":null,"uri":"USB/6436-3238/测试音频/PCM384/32_384k_PerfectTest.wav","trackType":"wav"} Oct 16 15:09:53 smsl-n100 volumio[990]: verbose: CURRENT POSITION 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState stateService play Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:09:53 smsl-n100 volumio[990]: info: Received an update from plugin. extracting info from payload Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreStateMachine::pushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: CorePlayQueue::getTrack 7 Oct 16 15:09:53 smsl-n100 volumio[990]: info: CoreCommandRouter::volumioPushState Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:09:53 smsl-n100 volumio[990]: info: MRS: Pushing multiroomSync output Oct 16 15:09:53 smsl-n100 volumio[990]: info: ------------------------------ 346ms Oct 16 15:09:56 smsl-n100 volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:09:56 smsl-n100 volumio[990]: Error: connect ETIMEDOUT 162.125.18.133:443 Oct 16 15:09:56 smsl-n100 volumio[990]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 16 15:09:56 smsl-n100 volumio[990]: errno: -110, Oct 16 15:09:56 smsl-n100 volumio[990]: code: 'ETIMEDOUT', Oct 16 15:09:56 smsl-n100 volumio[990]: syscall: 'connect', Oct 16 15:09:56 smsl-n100 volumio[990]: address: '162.125.18.133', Oct 16 15:09:56 smsl-n100 volumio[990]: port: 443 Oct 16 15:09:56 smsl-n100 volumio[990]: } Oct 16 15:09:56 smsl-n100 volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:09:58 smsl-n100 sudo[2943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 15:08 Oct 16 15:09:58 smsl-n100 sudo[2943]: 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"