-- Logs begin at Fri 2025-02-14 21:46:15 CST, end at Thu 2025-10-16 15:36:23 CST. -- Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::ClearQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 10 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::serviceStop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 10 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::serviceStop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::stop Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::clearPlayQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPlay Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::play index 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::play index undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 97 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 24 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 18 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand clear took 16 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 13 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 11 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 8 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac" Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 155ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 154ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 152ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: error: updateQueue error: null Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 169ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 162 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac" took 162 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 21ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 19ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 17ms Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:35:03 smsl-n100 volumio[2960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 196ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 99ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand play took 86 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 85ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 83ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ------------------------------ 80ms Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 32 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 30 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 25 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 22 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 17 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Oct 16 15:35:03 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:03 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 84 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 81 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 73 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 69 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: info: sendMpdCommand status took 65 milliseconds Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"985 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"985 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"985 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:03 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"985 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:03 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:03 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:03 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:03 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"985 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 230ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 287ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 279ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 276ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 272ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 210 milliseconds Oct 16 15:35:04 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 210 milliseconds Oct 16 15:35:04 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 209 milliseconds Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"MOZART Violin Concerto in D major KV 218, I. Allegro","artist":"Marianne Thorsen & TrondheimSolistene","album":"MOZART Violin Concertos (MQA remix 2016)","uri":"USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac","trackType":"flac"} Oct 16 15:35:04 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:04 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 405ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 404ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: ------------------------------ 400ms Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:04 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:04 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:04 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:04 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:04 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::ClearQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::serviceStop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 11 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::serviceStop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::stop Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::clearPlayQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPlay Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::play index 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::play index undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 109 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 24 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 25 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand clear took 24 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 22 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 14 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 11 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac" Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 162ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 161ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 155ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: error: updateQueue error: null Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 165ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 159 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac" took 159 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 17ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 15ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 13ms Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:35:15 smsl-n100 volumio[2960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 200ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 137ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand play took 129 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 128ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 125ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ------------------------------ 124ms Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: Oct 16 15:35:15 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:15 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:15 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:15 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:15 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:15 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 79 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 77 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 70 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 66 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: info: sendMpdCommand status took 59 milliseconds Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:15 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Oct 16 15:35:16 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Oct 16 15:35:16 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Oct 16 15:35:16 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 76 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 75 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 75 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand status took 68 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand status took 64 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand status took 60 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 300ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 381ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 368ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 365ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 362ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 212 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1671 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":370,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1426 Kbps","isStreaming":false,"title":"Get Lucky (feat. Pharrell Williams)","artist":"Daft Punk","album":"Random Access Memories","uri":"USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac","trackType":"flac"} Oct 16 15:35:16 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:16 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 453ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 451ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: ------------------------------ 448ms Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:16 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:16 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:16 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:16 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:16 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:31 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::ClearQueue Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:31 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::serviceStop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::serviceStop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::stop Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::clearPlayQueue Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/._MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPlay Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::play index 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::play index undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 86 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 23 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 18 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand clear took 16 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 14 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 12 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 8 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac" Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 150ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 149ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 148ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: error: updateQueue error: null Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 160ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 153 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac" took 154 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 17ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 14ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 12ms Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:35:32 smsl-n100 volumio[2960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 186ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 94ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand play took 87 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 84ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 80ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 76ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 38 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 35 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 31 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 27 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 23 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Oct 16 15:35:32 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 92 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 92 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 90 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 89 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 83 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 79 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand status took 75 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1745 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1745 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1745 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1745 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1745 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 246ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 296ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 286ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 287ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 285ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 203 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 203 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 203 milliseconds Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1664 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1664 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1664 Kbps","isStreaming":false,"title":"Never Going Back Again","artist":"Fleetwood Mac","album":"Rumours","uri":"USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac","trackType":"flac"} Oct 16 15:35:32 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:32 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 424ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 422ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: ------------------------------ 418ms Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:32 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:32 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:32 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::ClearQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:44 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:44 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::serviceStop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 13 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::serviceStop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: ControllerMpd::stop Oct 16 15:35:44 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::clearPlayQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPlay Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::play index 12 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::play index undefined Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:35:44 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:44 smsl-n100 volumio[2960]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:35:44 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Oct 16 15:35:44 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:44 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 146 milliseconds Oct 16 15:35:44 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:44 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:44 smsl-n100 volumio[2960]: info: sendMpdCommand stop took 43 milliseconds Oct 16 15:35:44 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:35:44 smsl-n100 volumio[2960]: info: Oct 16 15:35:44 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:44 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 23 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand clear took 22 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 19 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 17 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 15 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav" Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: No code Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 155ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 154ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 153ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: error: updateQueue error: null Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 162ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 156 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand add "USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav" took 155 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 19ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 18ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 15ms Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:35:45 smsl-n100 volumio[2960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 194ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces system playlist update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Ignoring MPD Status Update Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 96ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand play took 89 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 86ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 84ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 83ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 66 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 65 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 59 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 55 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 50 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Oct 16 15:35:45 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 54 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 48 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 45 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand status took 42 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 234ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 288ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 277ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 274ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 271ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 152 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 152 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 151 milliseconds Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:45 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:35:45 smsl-n100 volumio[2960]: info: Received an update from plugin. extracting info from payload Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 321ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 319ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: ------------------------------ 317ms Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:45 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:45 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:45 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::servicePause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::pause Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand pause took 5 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: Oct 16 15:35:48 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:48 smsl-n100 volumio[2960]: info: Oct 16 15:35:48 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:48 smsl-n100 volumio[2960]: info: Oct 16 15:35:48 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:48 smsl-n100 volumio[2960]: info: Oct 16 15:35:48 smsl-n100 volumio[2960]: ---------------------------- MPD announces state update: player Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::getState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand status took 18 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand status took 15 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand status took 8 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseState Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4964,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4964,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4964,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:48 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus pause Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:48 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:48 smsl-n100 volumio[2960]: info: ControllerMpd::pushState Oct 16 15:35:48 smsl-n100 volumio[2960]: info: CoreCommandRouter::servicePushState Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:49 smsl-n100 volumio[2960]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4964,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17.1KHz Sine Wave, -60 dB, L & R, emphasis off","artist":null,"album":"SONY YEDS-18 TEST CD","uri":"USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav","trackType":"wav"} Oct 16 15:35:49 smsl-n100 volumio[2960]: verbose: CURRENT POSITION 12 Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState stateService pause Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreStateMachine::syncState currentStatus pause Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:49 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:49 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:35:49 smsl-n100 volumio[2960]: info: ------------------------------ 142ms Oct 16 15:35:49 smsl-n100 volumio[2960]: info: ------------------------------ 137ms Oct 16 15:35:49 smsl-n100 volumio[2960]: info: ------------------------------ 132ms Oct 16 15:35:49 smsl-n100 volumio[2960]: info: ------------------------------ 130ms Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:35:49 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:35:49 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:35:49 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:35:49 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:49 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 15:35:49 smsl-n100 volumio[2960]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 15:35:49 smsl-n100 volumio[2960]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 15:36:02 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 5 Oct 16 15:36:02 smsl-n100 volumio[2960]: info: Oct 16 15:36:02 smsl-n100 volumio[2960]: ---------------------------- USB Audio Device Detached Oct 16 15:36:02 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 16 15:36:02 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 15:36:02 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:02 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 16 15:36:02 smsl-n100 volumio[2960]: info: Stopping RAAT Plugin Oct 16 15:36:02 smsl-n100 sudo[7453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 16 15:36:02 smsl-n100 sudo[7453]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:02 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Oct 16 15:36:02 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 16 15:36:02 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Oct 16 15:36:02 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Oct 16 15:36:02 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:02 smsl-n100 sudo[7453]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:02 smsl-n100 volumio[2960]: info: Raat Daemon stopped successfully Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.14 Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: Product: SMSL USB AUDIO Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: Manufacturer: SMSL Oct 16 15:36:17 smsl-n100 kernel: usb 4-1: 1:3 : unsupported format bits 0x100000000 Oct 16 15:36:17 smsl-n100 kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0.auto-1/input3 Oct 16 15:36:17 smsl-n100 systemd-udevd[7516]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 16 15:36:17 smsl-n100 volumio[2960]: info: Oct 16 15:36:17 smsl-n100 volumio[2960]: ---------------------------- USB Audio Device Attached Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 16 15:36:17 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreCommandRouter::Close All Modals sent Oct 16 15:36:17 smsl-n100 volumio[2960]: info: Preparing to save Alsa Options, stopping services first Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioGetState Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPause Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreStateMachine::pause Oct 16 15:36:17 smsl-n100 volumio[2960]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Oct 16 15:36:17 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 15:36:17 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 15:36:18 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Device 5 does not have any Mixer Control Available Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 16 15:36:18 smsl-n100 vtcs[5215]: [2025-10-16 15:36:18.136] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 16 15:36:18 smsl-n100 vtcs[5215]: [2025-10-16 15:36:18.134] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 16 15:36:18 smsl-n100 sudo[7531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 15:36:18 smsl-n100 sudo[7531]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:18 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Oct 16 15:36:18 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 16 15:36:18 smsl-n100 systemd[1]: vtcs.service: Succeeded. Oct 16 15:36:18 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Oct 16 15:36:18 smsl-n100 sudo[7531]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:18 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Disabling external Volume Control Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:36:18 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:36:18 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Preparing to generate the ALSA configuration file Oct 16 15:36:18 smsl-n100 volumio[2960]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Reading ALSA contributions from plugins. Oct 16 15:36:18 smsl-n100 volumio[2960]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Updating RAAT Signal Path Oct 16 15:36:18 smsl-n100 volumio[2960]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 15:36:18 smsl-n100 sudo[7539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 15:36:18 smsl-n100 sudo[7539]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:18 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:18 smsl-n100 sudo[7539]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:18 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 15:36:18 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:18 smsl-n100 volumio[2960]: info: Not Reporting Auto name since its the default one Oct 16 15:36:18 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Not Reporting Auto name since its the default one Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 15:36:19 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Asound.conf file unchanged, so no further update is needed Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Output device has changed, restarting MPD Oct 16 15:36:19 smsl-n100 sudo[7571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 16 15:36:19 smsl-n100 sudo[7571]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 sudo[7571]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 sudo[7574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 15:36:19 smsl-n100 sudo[7574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 sudo[7574]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Output device has changed, restarting Shairport Sync Oct 16 15:36:19 smsl-n100 sudo[7575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 16 15:36:19 smsl-n100 sudo[7575]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:36:19 smsl-n100 sudo[7578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 15:36:19 smsl-n100 sudo[7578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 systemd[1]: Started RAAT DAEMON. Oct 16 15:36:19 smsl-n100 sudo[7575]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 systemd[1]: Stopping Music Player Daemon... Oct 16 15:36:19 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 15:36:19 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:19 smsl-n100 volumio[2960]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:19 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 16 15:36:19 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 15:36:19 smsl-n100 sudo[7597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 15:36:19 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 15:36:19 smsl-n100 sudo[7597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 sudo[7600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 15:36:19 smsl-n100 sudo[7600]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 sudo[7597]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 sudo[7600]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:19 smsl-n100 sudo[7595]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 15:36:19 smsl-n100 sudo[7595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 sudo[7595]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:19 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 15:36:19 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:19 smsl-n100 sudo[7609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 16 15:36:19 smsl-n100 sudo[7609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:19 smsl-n100 volumio[2960]: info: MPD Permissions set Oct 16 15:36:19 smsl-n100 sudo[7609]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:19 smsl-n100 volumio[2960]: info: Raat Daemon started successfully Oct 16 15:36:20 smsl-n100 volumio[2960]: info: Executing endpoint restartRAATSocket Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 16 15:36:20 smsl-n100 sudo[7614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 16 15:36:20 smsl-n100 sudo[7614]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:20 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Oct 16 15:36:20 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 16 15:36:20 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Oct 16 15:36:20 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Oct 16 15:36:20 smsl-n100 systemd[1]: Started RAAT DAEMON. Oct 16 15:36:20 smsl-n100 sudo[7614]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: Not Reporting Auto name since its the default one Oct 16 15:36:20 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:20 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 15:36:20 smsl-n100 volumio[2960]: info: Raat Daemon started successfully Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 15:36:20 smsl-n100 volumio[2960]: xcb_connection_has_error() returned true Oct 16 15:36:20 smsl-n100 volumio[2960]: info: Executing endpoint restartRAATSocket Oct 16 15:36:20 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 16 15:36:20 smsl-n100 sudo[7640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 16 15:36:20 smsl-n100 sudo[7640]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:20 smsl-n100 sudo[7640]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:20 smsl-n100 volumio[2960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 16 15:36:20 smsl-n100 volumio[2960]: info: Starting Shairport Sync Oct 16 15:36:20 smsl-n100 sudo[7643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 16 15:36:20 smsl-n100 sudo[7643]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:20 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Oct 16 15:36:20 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 16 15:36:20 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Oct 16 15:36:20 smsl-n100 sudo[7646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 15:36:20 smsl-n100 sudo[7646]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:20 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Oct 16 15:36:20 smsl-n100 systemd[1]: Started RAAT DAEMON. Oct 16 15:36:20 smsl-n100 sudo[7643]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:21 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:21 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Raat Daemon started successfully Oct 16 15:36:21 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 15:36:21 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 16 15:36:21 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 15:36:21 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 15:36:21 smsl-n100 sudo[7646]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:21 smsl-n100 volumio[2960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 16 15:36:21 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:21 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Shairport-Sync Started Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Executing endpoint restartRAATSocket Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 16 15:36:21 smsl-n100 volumio[2960]: info: TidalConnect service stoped! Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:21 smsl-n100 volumio[2960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: TidalConnect service stoped! Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::ClearQueue Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::pushState Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushState Oct 16 15:36:21 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output update for this device Oct 16 15:36:21 smsl-n100 sudo[7671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 16 15:36:21 smsl-n100 volumio[2960]: info: MRS: Pushing multiroomSync output Oct 16 15:36:21 smsl-n100 sudo[7671]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::serviceStop Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 12 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::serviceStop Oct 16 15:36:21 smsl-n100 volumio[2960]: info: ControllerMpd::stop Oct 16 15:36:21 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::clearPlayQueue Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/12.月儿圆.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/13.想亲娘.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/14.美丽中国.WAV Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/768k-32bit.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/Absolute Sine.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/検品用.mp3 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/蔡依林 - 大艺术家.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:36:21 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:36:21 smsl-n100 sudo[7671]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:21 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:21 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:21 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:21 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:21 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:21 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPlay Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::play index 7 Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CoreStateMachine::addQueueItems Oct 16 15:36:21 smsl-n100 volumio[2960]: info: CorePlayQueue::addQueueItems Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Preload queue cleared Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/705.6k_32bit.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/2L38_01_24b 96kHz.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/莫扎特：D大调小提琴协奏曲 - 快板-2016重新混音-2L-038_MQA2016-352k-24b_01.flac Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/17. 1KHz Sine Wave, -60 dB, L & R, emphasis off.wav Oct 16 15:36:21 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/24bit_1k_0dBFS_30sec Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/26 tracks Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/ALL FS Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/DSD Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:36:22 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 15:36:22 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:36:22 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/PCM384 Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Using cached record of: music-library/USB/6436-3238/测试音频/新建文件夹 (2) Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::stop Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:36:22 smsl-n100 sudo[7578]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CorePlayQueue::saveQueue Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::play index undefined Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::updateTrackBlock Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrackBlock Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 7 Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:36:22 smsl-n100 volumio[2960]: info: CorePlayQueue::getTrack 7 Oct 16 15:36:22 smsl-n100 volumio[2960]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/左右声道测试.mp3 Oct 16 15:36:22 smsl-n100 volumio[2960]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:36:22 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:22 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:22 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:22 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:22 smsl-n100 volumio[2960]: error: MPD error: Error: This socket has been ended by the other party Oct 16 15:36:22 smsl-n100 volumio[2960]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 16 15:36:22 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 15:36:22 smsl-n100 volumio[2960]: info: Updating RAAT Signal Path Oct 16 15:36:22 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 15:36:22 smsl-n100 volumio[2960]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 15:36:22 smsl-n100 volumio[2960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:36:22 smsl-n100 volumio[2960]: Error: connect ETIMEDOUT 199.59.150.44:443 Oct 16 15:36:22 smsl-n100 volumio[2960]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 16 15:36:22 smsl-n100 volumio[2960]: errno: -110, Oct 16 15:36:22 smsl-n100 volumio[2960]: code: 'ETIMEDOUT', Oct 16 15:36:22 smsl-n100 volumio[2960]: syscall: 'connect', Oct 16 15:36:22 smsl-n100 volumio[2960]: address: '199.59.150.44', Oct 16 15:36:22 smsl-n100 volumio[2960]: port: 443 Oct 16 15:36:22 smsl-n100 volumio[2960]: } Oct 16 15:36:22 smsl-n100 volumio[2960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:36:22 smsl-n100 sudo[7678]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 15:36:22 smsl-n100 sudo[7678]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 15:36:22 smsl-n100 sudo[7678]: pam_unix(sudo:session): session closed for user root Oct 16 15:36:23 smsl-n100 sudo[7691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 15:35 Oct 16 15:36:23 smsl-n100 sudo[7691]: 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"