-- Logs begin at Fri 2025-02-14 21:46:15 CST, end at Thu 2025-10-16 16:18:48 CST. -- Oct 16 16:17:10 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::ClearQueue Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 6 Oct 16 16:17:10 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::serviceStop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 6 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::serviceStop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::stop Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::clearPlayQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::addQueueItems Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::addQueueItems Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPlay Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::play index 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::addQueueItems Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::addQueueItems Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::play index undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::startPlaybackTimer Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 81 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 20 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 17 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand clear took 16 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 13 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 11 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 9 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac" Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 153ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 153ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 156ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: error: updateQueue error: null Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 169ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 162 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac" took 162 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 18ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 15ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 13ms Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand play Oct 16 16:17:11 smsl-n100 volumio[3058]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 196ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 139ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand play took 131 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 128ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 125ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 121ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 26 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 24 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 19 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 17 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 13 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Oct 16 16:17:11 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:11 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 87 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 85 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 79 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 75 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand status took 71 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 257ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 322ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 314ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 311ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 309ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 284 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 283 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 283 milliseconds Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":555,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 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 16:17:11 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:11 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:11 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:11 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 521ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 520ms Oct 16 16:17:11 smsl-n100 volumio[3058]: info: ------------------------------ 517ms Oct 16 16:17:12 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:12 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:12 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:12 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:12 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:12 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:12 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:12 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:12 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::ClearQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::serviceStop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 7 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::serviceStop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::stop Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::clearPlayQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::addQueueItems Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::addQueueItems Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPlay Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::play index 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::addQueueItems Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::addQueueItems Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::play index undefined Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::startPlaybackTimer Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Oct 16 16:17:16 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 107 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 38 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Oct 16 16:17:16 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Oct 16 16:17:16 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Oct 16 16:17:16 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Oct 16 16:17:16 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:16 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand status took 34 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand clear took 32 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand status took 29 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand status took 18 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: info: sendMpdCommand status took 14 milliseconds Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac" Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:16 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: 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 16:17:16 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: 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 16:17:16 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:16 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:16 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:16 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 181ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 179ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 169ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: error: updateQueue error: null Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 177ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 166 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac" took 166 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 17ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 15ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 13ms Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand play Oct 16 16:17:17 smsl-n100 volumio[3058]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 220ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 135ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand play took 127 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 121ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 119ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 115ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 36 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 35 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 31 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 28 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 26 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Oct 16 16:17:17 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand status took 38 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 187ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 237ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 229ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 227ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 226ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 201 milliseconds Oct 16 16:17:17 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: 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 16:17:17 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:17 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: info: ------------------------------ 293ms Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:17 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:17 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:17 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::ClearQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::serviceStop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 8 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::serviceStop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::stop Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::clearPlayQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::addQueueItems Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::addQueueItems Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Preload queue cleared Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/DoP64.wav Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/030 44100 UI Complete Test.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/MAGENTA 037 Original Rate 384000 24bit.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/080 48000 UI Complete Test.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Coldplay - Adventure Of A Lifetime.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-139_stereo_FLAC_352k_24b_01.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-106_stereo_PCM-192k_MAGNIFICAT_04.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/2L-038-MQA-2016_01_stereo.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Daft Punk - Get Lucky (feat. Pharrell Williams).mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Adding Item to queue: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Using cached record of: music-library/USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::saveQueue Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::updateTrackBlock Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrackBlock Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPlay Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::play index 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::play index undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::startPlaybackTimer Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::clearAddPlayTracks USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 73 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand stop took 23 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 18 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand clear took 15 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 13 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 10 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 8 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand add "USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac" Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: 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 16:17:19 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: 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 16:17:19 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: 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 16:17:19 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: No code Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:19 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:19 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 135ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 133ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 133ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: error: updateQueue error: null Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 144ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 137 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand add "USB/6436-3238/测试音频/MQA/Fleetwood Mac - Never Going Back Again.mqa.flac" took 138 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 16ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 14ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 12ms Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand play Oct 16 16:17:19 smsl-n100 volumio[3058]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 170ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces system playlist update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Ignoring MPD Status Update Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 122ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand play took 116 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 113ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 110ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ------------------------------ 107ms Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 48 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 44 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 40 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 36 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 31 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: Oct 16 16:17:19 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:19 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 15 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 13 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: info: sendMpdCommand status took 10 milliseconds Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:19 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 185ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 219ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 208ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 204ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 201ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:20 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 156 milliseconds Oct 16 16:17:20 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 156 milliseconds Oct 16 16:17:20 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 155 milliseconds Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1818 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1602 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1602 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 16:17:20 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:20 smsl-n100 volumio[3058]: info: Received an update from plugin. extracting info from payload Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 277ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 276ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: ------------------------------ 274ms Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:20 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:17:20 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:17:20 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:17:34 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 6 Oct 16 16:17:34 smsl-n100 kernel: usb 2-1.4: cannot submit urb (err = -19) Oct 16 16:17:34 smsl-n100 kernel: usb 2-1.4: cannot submit urb (err = -19) Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Oct 16 16:17:34 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Oct 16 16:17:34 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Oct 16 16:17:34 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Oct 16 16:17:34 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand status took 28 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand status took 25 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand status took 23 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand status took 22 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 36 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 34 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 31 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 29 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16779,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:17:34 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16779,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:17:34 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16779,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:17:34 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16779,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:17:34 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:17:34 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ------------------------------ 163ms Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ------------------------------ 153ms Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ------------------------------ 148ms Oct 16 16:17:34 smsl-n100 volumio[3058]: info: ------------------------------ 144ms Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Oct 16 16:17:34 smsl-n100 volumio[3058]: ---------------------------- USB Audio Device Detached Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:17:34 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:17:34 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 16 16:17:34 smsl-n100 volumio[3058]: info: Stopping RAAT Plugin Oct 16 16:17:35 smsl-n100 sudo[6985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 16 16:17:35 smsl-n100 sudo[6985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:17:35 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Oct 16 16:17:35 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 16 16:17:35 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Oct 16 16:17:35 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Oct 16 16:17:35 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:17:35 smsl-n100 sudo[6985]: pam_unix(sudo:session): session closed for user root Oct 16 16:17:35 smsl-n100 volumio[3058]: info: Raat Daemon stopped successfully Oct 16 16:17:44 smsl-n100 kernel: usb usb4-port1: Cannot enable. Maybe the USB cable is bad? Oct 16 16:17:45 smsl-n100 kernel: usb usb4-port1: Cannot enable. Maybe the USB cable is bad? Oct 16 16:17:45 smsl-n100 kernel: usb usb4-port1: attempt power cycle Oct 16 16:17:46 smsl-n100 kernel: usb usb4-port1: Cannot enable. Maybe the USB cable is bad? Oct 16 16:17:47 smsl-n100 kernel: usb usb4-port1: Cannot enable. Maybe the USB cable is bad? Oct 16 16:17:47 smsl-n100 kernel: usb usb4-port1: unable to enumerate USB device Oct 16 16:18:36 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioGetState Oct 16 16:18:36 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:18:37 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:18:37 smsl-n100 volumio[3058]: Invalid card number. Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:37 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:38 smsl-n100 volumio[3058]: info: Executing endpoint metavolumio Oct 16 16:18:38 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 16:18:38 smsl-n100 volumio[3058]: info: Executing endpoint metavolumio Oct 16 16:18:38 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 16:18:38 smsl-n100 volumio[3058]: info: Executing endpoint metavolumio Oct 16 16:18:38 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 16 16:18:39 smsl-n100 volumio[3058]: info: Preparing to save Alsa Options, stopping services first Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioGetState Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPause Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreStateMachine::pause Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreStateMachine::servicePause Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePause Oct 16 16:18:39 smsl-n100 volumio[3058]: info: ControllerMpd::pause Oct 16 16:18:39 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand pause Oct 16 16:18:39 smsl-n100 volumio[3058]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}} Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:18:39 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:39 smsl-n100 volumio[3058]: warn: Unable to locate the device SMSL USB AUDIO a reboot may be required. Oct 16 16:18:39 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:18:39 smsl-n100 volumio[3058]: Invalid card number. Oct 16 16:18:39 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 16 16:18:39 smsl-n100 volumio[3058]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 16 16:18:39 smsl-n100 volumio[3058]: info: Disabling external Volume Control Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:39 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:39 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:39 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:39 smsl-n100 volumio[3058]: info: Preparing to generate the ALSA configuration file Oct 16 16:18:40 smsl-n100 volumio[3058]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Reading ALSA contributions from plugins. Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Oct 16 16:18:40 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand pause took 469 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Oct 16 16:18:40 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Oct 16 16:18:40 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Oct 16 16:18:40 smsl-n100 volumio[3058]: ---------------------------- MPD announces state update: player Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::getState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand status took 38 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand status took 37 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand status took 36 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand status took 35 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseState Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 30 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 28 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 26 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand clearerror took 25 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:18:40 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:18:40 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:18:40 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ControllerMpd::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":125,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1675 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 16:18:40 smsl-n100 volumio[3058]: verbose: CURRENT POSITION 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState stateService pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::syncState currentStatus pause Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::stPlaybackTimer Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ------------------------------ 152ms Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ------------------------------ 150ms Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ------------------------------ 147ms Oct 16 16:18:40 smsl-n100 volumio[3058]: info: ------------------------------ 144ms Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:18:40 smsl-n100 volumio[3058]: Invalid card number. Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:18:40 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Asound.conf file written Oct 16 16:18:40 smsl-n100 sudo[7177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 16 16:18:40 smsl-n100 sudo[7177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:40 smsl-n100 sudo[7177]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:40 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:40 smsl-n100 volumio[3058]: No state is present for card Out Oct 16 16:18:40 smsl-n100 volumio[3058]: Found hardware: "I2S_Out" "" "" "" "" Oct 16 16:18:40 smsl-n100 volumio[3058]: Hardware is initialized using a generic method Oct 16 16:18:40 smsl-n100 volumio[3058]: No state is present for card Out Oct 16 16:18:40 smsl-n100 volumio[3058]: No state is present for card SPDIF Oct 16 16:18:40 smsl-n100 volumio[3058]: Found hardware: "SPDIF" "" "" "" "" Oct 16 16:18:40 smsl-n100 volumio[3058]: Hardware is initialized using a generic method Oct 16 16:18:40 smsl-n100 volumio[3058]: No state is present for card SPDIF Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Output device has changed, restarting MPD Oct 16 16:18:40 smsl-n100 sudo[7182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 16:18:40 smsl-n100 sudo[7182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:40 smsl-n100 sudo[7182]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Output device has changed, restarting Shairport Sync Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:40 smsl-n100 sudo[7185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 16:18:40 smsl-n100 sudo[7185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:40 smsl-n100 systemd[1]: Stopping Music Player Daemon... Oct 16 16:18:40 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 16 16:18:40 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:40 smsl-n100 volumio[3058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 16:18:40 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:40 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 16 16:18:40 smsl-n100 volumio[3058]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 16 16:18:40 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:40 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 vtcs[5449]: [2025-10-16 16:18:41.028] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 16 16:18:41 smsl-n100 vtcs[5449]: [2025-10-16 16:18:41.030] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 16 16:18:41 smsl-n100 vtcs[5449]: [2025-10-16 16:18:41.030] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Output device has changed, restarting MPD Oct 16 16:18:41 smsl-n100 sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:41 smsl-n100 sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 16:18:41 smsl-n100 sudo[7190]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 16:18:41 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Oct 16 16:18:41 smsl-n100 sudo[7198]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 16 16:18:41 smsl-n100 systemd[1]: vtcs.service: Succeeded. Oct 16 16:18:41 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Oct 16 16:18:41 smsl-n100 sudo[7198]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Output device has changed, restarting Shairport Sync Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:41 smsl-n100 sudo[7190]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 sudo[7202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 16:18:41 smsl-n100 sudo[7195]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 sudo[7202]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 16:18:41 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 16 16:18:41 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 16 16:18:41 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 16:18:41 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 16:18:41 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:41 smsl-n100 volumio[3058]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 sudo[7215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:41 smsl-n100 sudo[7215]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 16:18:41 smsl-n100 sudo[7209]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:41 smsl-n100 sudo[7218]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7209]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 sudo[7215]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 sudo[7223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:41 smsl-n100 sudo[7223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:41 smsl-n100 sudo[7218]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 sudo[7223]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:41 smsl-n100 volumio[3058]: info: Not Reporting Auto name since its the default one Oct 16 16:18:41 smsl-n100 volumio[3058]: info: VolumeController::SetAlsaVolume0 Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:41 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:41 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:41 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:41 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:42 smsl-n100 volumio[3058]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 16 16:18:42 smsl-n100 volumio[3058]: info: MPD Permissions set Oct 16 16:18:42 smsl-n100 volumio[3058]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 16 16:18:42 smsl-n100 volumio[3058]: info: MPD Permissions set Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Not Reporting Auto name since its the default one Oct 16 16:18:42 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:42 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 16:18:42 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Configured Audio card not found, not starting RAAT Oct 16 16:18:42 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 16:18:42 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Configured Audio card not found, not starting RAAT Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Starting Shairport Sync Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Starting Shairport Sync Oct 16 16:18:42 smsl-n100 sudo[7253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 16:18:42 smsl-n100 sudo[7253]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Preparing to generate the ALSA configuration file Oct 16 16:18:42 smsl-n100 sudo[7256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 16:18:42 smsl-n100 sudo[7256]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:42 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 16:18:42 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 16 16:18:42 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:42 smsl-n100 volumio[3058]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Reading ALSA contributions from plugins. Oct 16 16:18:42 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:42 smsl-n100 sudo[7253]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:42 smsl-n100 sudo[7256]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:42 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:42 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:42 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Shairport-Sync Started Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Shairport-Sync Started Oct 16 16:18:42 smsl-n100 volumio[3058]: info: Asound.conf file written Oct 16 16:18:43 smsl-n100 sudo[7262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 16 16:18:43 smsl-n100 sudo[7262]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 sudo[7262]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:43 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:43 smsl-n100 volumio[3058]: No state is present for card Out Oct 16 16:18:43 smsl-n100 volumio[3058]: Found hardware: "I2S_Out" "" "" "" "" Oct 16 16:18:43 smsl-n100 volumio[3058]: Hardware is initialized using a generic method Oct 16 16:18:43 smsl-n100 volumio[3058]: No state is present for card Out Oct 16 16:18:43 smsl-n100 volumio[3058]: No state is present for card SPDIF Oct 16 16:18:43 smsl-n100 volumio[3058]: Found hardware: "SPDIF" "" "" "" "" Oct 16 16:18:43 smsl-n100 volumio[3058]: Hardware is initialized using a generic method Oct 16 16:18:43 smsl-n100 volumio[3058]: No state is present for card SPDIF Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Output device has changed, restarting MPD Oct 16 16:18:43 smsl-n100 sudo[7267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 16:18:43 smsl-n100 sudo[7267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Output device has changed, restarting Shairport Sync Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:43 smsl-n100 sudo[7267]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:43 smsl-n100 sudo[7270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 16:18:43 smsl-n100 sudo[7270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 16:18:43 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 16 16:18:43 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 16 16:18:43 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 16:18:43 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 16:18:43 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:43 smsl-n100 volumio[3058]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Preparing to generate the ALSA configuration file Oct 16 16:18:43 smsl-n100 sudo[7279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:43 smsl-n100 sudo[7279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 sudo[7275]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 16:18:43 smsl-n100 sudo[7275]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 sudo[7275]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:43 smsl-n100 sudo[7279]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:43 smsl-n100 volumio[3058]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Reading ALSA contributions from plugins. Oct 16 16:18:43 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:43 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:43 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:43 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:43 smsl-n100 sudo[7284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:43 smsl-n100 volumio[3058]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 16 16:18:43 smsl-n100 volumio[3058]: info: MPD Permissions set Oct 16 16:18:43 smsl-n100 sudo[7284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 sudo[7284]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreStateMachine::getcurrentVolume Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:43 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:43 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:43 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:43 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:43 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:43 smsl-n100 volumio[3058]: info: Starting Shairport Sync Oct 16 16:18:44 smsl-n100 sudo[7295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 16:18:44 smsl-n100 sudo[7295]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Not Reporting Auto name since its the default one Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Asound.conf file unchanged, so no further update is needed Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Output device has changed, restarting MPD Oct 16 16:18:44 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 16:18:44 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 16 16:18:44 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:44 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:44 smsl-n100 sudo[7295]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Output device has changed, restarting Shairport Sync Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:44 smsl-n100 sudo[7303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 16:18:44 smsl-n100 sudo[7303]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 sudo[7303]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:44 smsl-n100 sudo[7307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 16:18:44 smsl-n100 sudo[7307]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 16:18:44 smsl-n100 systemd[1]: mpd.service: Succeeded. Oct 16 16:18:44 smsl-n100 systemd[1]: Stopped Music Player Daemon. Oct 16 16:18:44 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 16:18:44 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:44 smsl-n100 volumio[3058]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 systemd[1]: Starting Music Player Daemon... Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:44 smsl-n100 sudo[7318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:44 smsl-n100 sudo[7318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 16:18:44 smsl-n100 sudo[7314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 16:18:44 smsl-n100 sudo[7314]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 sudo[7321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 16 16:18:44 smsl-n100 sudo[7321]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:44 smsl-n100 sudo[7314]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:44 smsl-n100 sudo[7318]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:44 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Configured Audio card not found, not starting RAAT Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:44 smsl-n100 sudo[7321]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:44 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:44 smsl-n100 volumio[3058]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 16 16:18:44 smsl-n100 volumio[3058]: info: MPD Permissions set Oct 16 16:18:44 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:44 smsl-n100 volumio[3058]: info: Shairport-Sync Started Oct 16 16:18:44 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:44 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:44 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Not Reporting Auto name since its the default one Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:45 smsl-n100 sudo[7352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 16 16:18:45 smsl-n100 sudo[7352]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Starting Shairport Sync Oct 16 16:18:45 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Oct 16 16:18:45 smsl-n100 sudo[7352]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 16 16:18:45 smsl-n100 sudo[7362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 16:18:45 smsl-n100 sudo[7362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:45 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 16:18:45 smsl-n100 volumio[3058]: xcb_connection_has_error() returned true Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Configured Audio card not found, not starting RAAT Oct 16 16:18:45 smsl-n100 volumio[3058]: info: RAAT: Requesting Headphone Status Oct 16 16:18:45 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 16 16:18:45 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:45 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 16:18:45 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 16:18:45 smsl-n100 sudo[7362]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:45 smsl-n100 volumio[3058]: info: RAAT: Requesting Headphone Status Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Failed request for metavolumio API Oct 16 16:18:45 smsl-n100 volumio[3058]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 16:18:45 smsl-n100 sudo[7382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 16 16:18:45 smsl-n100 sudo[7382]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:45 smsl-n100 volumio[3058]: info: Shairport-Sync Started Oct 16 16:18:45 smsl-n100 volumio[3058]: (node:3058) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 16 16:18:45 smsl-n100 volumio[3058]: (node:3058) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 16 16:18:45 smsl-n100 volumio[3058]: (node:3058) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 16 16:18:45 smsl-n100 volumio[3058]: (node:3058) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 16 16:18:45 smsl-n100 volumio[3058]: (node:3058) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:45 smsl-n100 sudo[7382]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:45 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 16 16:18:45 smsl-n100 volumio[3058]: error: Serial API: Failed to decode command: 100, message: undefined Oct 16 16:18:45 smsl-n100 volumio[3058]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Executing endpoint tc_getconfig Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 16 16:18:46 smsl-n100 vtcs[7359]: STARTING TidalConnect services, version: 1.4.0.34 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: VolumeController::SetAlsaVolume0 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 vtcs[7359]: STARTED TidalConnect services. Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Executing endpoint tc_connect Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Connecting to TidalConnect Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::servicePushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreStateMachine::pushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output update for this device Oct 16 16:18:46 smsl-n100 volumio[3058]: info: MRS: Pushing multiroomSync output Oct 16 16:18:46 smsl-n100 volumio[3058]: info: CorePlayQueue::getTrack 9 Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Updating RAAT Signal Path Oct 16 16:18:46 smsl-n100 volumio[3058]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 16 16:18:46 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:46 smsl-n100 volumio[3058]: info: TidalConnect service stoped! Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:46 smsl-n100 volumio[3058]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 16 16:18:47 smsl-n100 sudo[7412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 16 16:18:47 smsl-n100 sudo[7412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:47 smsl-n100 sudo[7412]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:47 smsl-n100 volumio[3058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:18:47 smsl-n100 volumio[3058]: Error: connect ETIMEDOUT 31.13.73.169:443 Oct 16 16:18:47 smsl-n100 volumio[3058]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 16 16:18:47 smsl-n100 volumio[3058]: errno: -110, Oct 16 16:18:47 smsl-n100 volumio[3058]: code: 'ETIMEDOUT', Oct 16 16:18:47 smsl-n100 volumio[3058]: syscall: 'connect', Oct 16 16:18:47 smsl-n100 volumio[3058]: address: '31.13.73.169', Oct 16 16:18:47 smsl-n100 volumio[3058]: port: 443 Oct 16 16:18:47 smsl-n100 volumio[3058]: } Oct 16 16:18:47 smsl-n100 volumio[3058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:18:48 smsl-n100 sudo[7425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 16:17 Oct 16 16:18:48 smsl-n100 sudo[7425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:48 smsl-n100 mpd[7326]: Oct 16 16:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 16:18:48 smsl-n100 systemd[1]: Started Music Player Daemon. Oct 16 16:18:48 smsl-n100 sudo[7185]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:48 smsl-n100 sudo[7202]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:48 smsl-n100 sudo[7307]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:48 smsl-n100 sudo[7270]: pam_unix(sudo:session): session closed for user root 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"