-- Logs begin at Thu 2024-10-17 09:14:08 CST, end at Sat 2024-10-26 15:22:38 CST. -- Oct 26 15:21:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:21:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:21:01 smsl-d400n volumio[4466]: info: Discovery: Getting this device information Oct 26 15:21:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:21:01 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:21:09 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri Oct 26 15:21:09 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:21:09 smsl-d400n volumio[4466]: info: Executing endpoint metavolumio Oct 26 15:21:09 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 15:21:10 smsl-d400n volumio[4466]: info: Executing endpoint metavolumio Oct 26 15:21:10 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::ClearQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::stPlaybackTimer Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::serviceStop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::serviceStop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::stop Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::clearPlayQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::addQueueItems Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::addQueueItems Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track0 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track0 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track1 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track2 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track2 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track3 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track3 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track4 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track4 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track5 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track5 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track6 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track6 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Oct 26 15:21:17 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand stop took 56 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand status took 3 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Oct 26 15:21:17 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Oct 26 15:21:17 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand status took 7 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2","artist":null,"album":null,"uri":"cdda:///2","trackType":"CD Audio"} Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:17 smsl-d400n volumio[4466]: info: No code Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 1 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:17 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ------------------------------ 93ms Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPlay Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::play index 6 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::addQueueItems Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::addQueueItems Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track7 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track7 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track8 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track8 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track9 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track9 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track10 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track10 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track11 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track11 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track12 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track12 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track13 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track13 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track14 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track14 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track15 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track15 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track16 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track16 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track17 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track17 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track18 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track18 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track19 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track19 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track20 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track20 in service cd_controller Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::play index undefined Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::clearAddPlayTracks cdda:///7 Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 239 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 239 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: info: sendMpdCommand stop took 3 milliseconds Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:21:17 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:21:17 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2","artist":null,"album":null,"uri":"cdda:///2","trackType":"CD Audio"} Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: No code Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2","artist":null,"album":null,"uri":"cdda:///2","trackType":"CD Audio"} Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: No code Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 380ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 380ms Oct 26 15:21:18 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:21:18 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: sendMpdCommand clear took 198 milliseconds Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand add "cdda:///7" Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: error: updateQueue error: null Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 16ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: sendMpdCommand add "cdda:///7" took 15 milliseconds Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 9ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 8ms Oct 26 15:21:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Oct 26 15:21:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 44ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: sendMpdCommand play took 40 milliseconds Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 39ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: ------------------------------ 37ms Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:21:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:21:18 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:21:18 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:21:18 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 6 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 11 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 6 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 59ms Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 88ms Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Oct 26 15:21:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 203ms Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 197ms Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 195ms Oct 26 15:21:19 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 161 milliseconds Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:21:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:21:19 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:21:19 smsl-d400n volumio[4466]: info: ------------------------------ 207ms Oct 26 15:21:19 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:21:19 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:21:19 smsl-d400n volumio[4466]: info: MCU Signalled Playback Active Oct 26 15:21:19 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:21:19 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:21:26 smsl-d400n volumiologrotate[719]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 26 15:21:26 smsl-d400n volumiologrotate[719]: ls: cannot access 'D400N': No such file or directory Oct 26 15:21:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:21:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:21:31 smsl-d400n volumio[4466]: info: Discovery: Getting this device information Oct 26 15:21:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:21:31 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:21:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:22:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:22:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:22:01 smsl-d400n volumio[4466]: info: Discovery: Getting this device information Oct 26 15:22:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:22:01 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:01 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioSeek Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::seek Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: TRACKBLOCK {"albumart":"/albumart?cacheid=947&web=Various%20Artists/2016%20GRAMMY%20Nominees/extralarge&path=%2Fmnt%2FUSB&icon=dot-circle-o&metadata=false","channels":2,"name":"Alabama Shakes / Don’t Wanna Fight","album":"2016 GRAMMY Nominees","artist":"Various Artists","samplerate":"44.1 KHz","bitdepth":"16 bit","service":"mpd","uri":"cdda:///7","trackType":"cd","duration":233} Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::seek Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: error: null Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Oct 26 15:22:05 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Oct 26 15:22:05 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Oct 26 15:22:05 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand status took 19 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand status took 16 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand status took 13 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139000,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139000,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139000,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:05 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:05 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:05 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:05 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:05 smsl-d400n volumio[4466]: info: ------------------------------ 124ms Oct 26 15:22:06 smsl-d400n volumio[4466]: info: ------------------------------ 122ms Oct 26 15:22:06 smsl-d400n volumio[4466]: info: ------------------------------ 121ms Oct 26 15:22:06 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::ClearQueue Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::stop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::stPlaybackTimer Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::serviceStop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::serviceStop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::stop Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::clearPlayQueue Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::addQueueItems Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::addQueueItems Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track0 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track0 in service cd_controller Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track1 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track1 in service cd_controller Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track2 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track2 in service cd_controller Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track3 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track3 in service cd_controller Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track4 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track4 in service cd_controller Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Oct 26 15:22:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand stop took 40 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Oct 26 15:22:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:18 smsl-d400n volumio[4466]: info: Oct 26 15:22:18 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand status took 5 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand status took 3 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:18 smsl-d400n volumio[4466]: info: No code Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:18 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ------------------------------ 64ms Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:18 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:18 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:18 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: No code Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7","artist":null,"album":null,"uri":"cdda:///7","trackType":"CD Audio"} Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: No code Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:19 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 159ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 159ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPlay Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::play index 4 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::addQueueItems Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::addQueueItems Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Preload queue cleared Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track5 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track5 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track6 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track6 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track7 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track7 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track8 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track8 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track9 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track9 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track10 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track10 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track11 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track11 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track12 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track12 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track13 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track13 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track14 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track14 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track15 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track15 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track16 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track16 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track17 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track17 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track18 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track18 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track19 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track19 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Adding Item to queue: cdaudio/track20 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Exploding uri cdaudio/track20 in service cd_controller Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::play index undefined Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::clearAddPlayTracks cdda:///5 Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 15:22:19 smsl-d400n volumio[4466]: info: sendMpdCommand stop took 49 milliseconds Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 15:22:19 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: sendMpdCommand clear took 11 milliseconds Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand add "cdda:///5" Oct 26 15:22:19 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: error: updateQueue error: null Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 15ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: sendMpdCommand add "cdda:///5" took 13 milliseconds Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 10ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 8ms Oct 26 15:22:19 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand play Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Oct 26 15:22:19 smsl-d400n volumio[4466]: ---------------------------- MPD announces system playlist update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: Ignoring MPD Status Update Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 15ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: sendMpdCommand play took 12 milliseconds Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 8ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: ------------------------------ 6ms Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushQueue Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::saveQueue Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CoreStateMachine::updateTrackBlock Oct 26 15:22:19 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrackBlock Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 8 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 3 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 10 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 6 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus stop Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 51ms Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 61ms Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Oct 26 15:22:20 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand status took 4 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 172ms Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 166ms Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 166ms Oct 26 15:22:20 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 142 milliseconds Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:20 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:20 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:20 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:20 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:20 smsl-d400n volumio[4466]: info: ------------------------------ 183ms Oct 26 15:22:20 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:20 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:22:21 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:21 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:21 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:24 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:22:24 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:22:24 smsl-d400n volumio[4466]: info: Discovery: Getting this device information Oct 26 15:22:24 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:22:24 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:24 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:22:24 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:25 smsl-d400n volumio[4466]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 15:22:25 smsl-d400n volumio[4466]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 15:22:25 smsl-d400n volumio[4466]: info: Listing playlists Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetQueue Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreStateMachine::getQueue Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CorePlayQueue::getQueue Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 15:22:25 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 15:22:26 smsl-d400n volumio[4466]: verbose: New Socket.io Connection to 192.168.2.9:3000 from 192.168.2.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Oct 26 15:22:26 smsl-d400n volumiologrotate[719]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 26 15:22:26 smsl-d400n volumiologrotate[719]: ls: cannot access 'D400N': No such file or directory Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioSeek Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::seek Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: TRACKBLOCK {"albumart":"/albumart?cacheid=947&web=Various%20Artists/2016%20GRAMMY%20Nominees/extralarge&path=%2Fmnt%2FUSB&icon=dot-circle-o&metadata=false","channels":2,"name":"Maroon 5 / Sugar","album":"2016 GRAMMY Nominees","artist":"Various Artists","samplerate":"44.1 KHz","bitdepth":"16 bit","service":"mpd","uri":"cdda:///5","trackType":"cd","duration":236} Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::startPlaybackTimer Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::seek Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: error: null Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Oct 26 15:22:28 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Oct 26 15:22:28 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Oct 26 15:22:28 smsl-d400n volumio[4466]: ---------------------------- MPD announces state update: player Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::getState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand status took 26 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand status took 23 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand status took 13 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseState Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":163000,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":163000,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ControllerMpd::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::servicePushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: STATE SERVICE {"status":"play","position":0,"seek":163000,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5","artist":null,"album":null,"uri":"cdda:///5","trackType":"CD Audio"} Oct 26 15:22:28 smsl-d400n volumio[4466]: verbose: CURRENT POSITION 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState stateService play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::syncState currentStatus play Oct 26 15:22:28 smsl-d400n volumio[4466]: info: Received an update from plugin. extracting info from payload Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreStateMachine::pushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:28 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioPushState Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output update for this device Oct 26 15:22:28 smsl-d400n volumio[4466]: info: MRS: Pushing multiroomSync output Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ------------------------------ 158ms Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ------------------------------ 157ms Oct 26 15:22:28 smsl-d400n volumio[4466]: info: ------------------------------ 154ms Oct 26 15:22:28 smsl-d400n volumio[4466]: error: Serial API: Failed to decode command: GET, message: STATE Oct 26 15:22:28 smsl-d400n volumio[4466]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/2016%20GRAMMY%20Nominees/6934b0eb-2d41-456d-b5d6-58bbba066d7a.jpg' Oct 26 15:22:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:22:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:22:31 smsl-d400n volumio[4466]: info: Discovery: Getting this device information Oct 26 15:22:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:22:31 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:31 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:22:37 smsl-d400n sudo[6972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 15:22:37 smsl-d400n sudo[6972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:22:37 smsl-d400n sudo[6972]: pam_unix(sudo:session): session closed for user root Oct 26 15:22:37 smsl-d400n sudo[6975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 15:22:37 smsl-d400n sudo[6975]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:22:37 smsl-d400n sudo[6975]: pam_unix(sudo:session): session closed for user root Oct 26 15:22:37 smsl-d400n volumio[4466]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Oct 26 15:22:37 smsl-d400n sudo[6978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 15:22:37 smsl-d400n sudo[6978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:22:37 smsl-d400n sudo[6978]: pam_unix(sudo:session): session closed for user root Oct 26 15:22:37 smsl-d400n sudo[6981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 15:22:37 smsl-d400n sudo[6981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:22:37 smsl-d400n sudo[6981]: pam_unix(sudo:session): session closed for user root Oct 26 15:22:37 smsl-d400n volumio[4466]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::volumioGetState Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CorePlayQueue::getTrack 4 Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 15:22:37 smsl-d400n volumio[4466]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 26 15:22:37 smsl-d400n volumio[4466]: info: Listing playlists Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 26 15:22:37 smsl-d400n volumio[4466]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 15:22:37 smsl-d400n volumio[4466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:22:37 smsl-d400n volumio[4466]: Error: connect ETIMEDOUT 128.242.245.29:443 Oct 26 15:22:37 smsl-d400n volumio[4466]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 26 15:22:37 smsl-d400n volumio[4466]: errno: -110, Oct 26 15:22:37 smsl-d400n volumio[4466]: code: 'ETIMEDOUT', Oct 26 15:22:37 smsl-d400n volumio[4466]: syscall: 'connect', Oct 26 15:22:37 smsl-d400n volumio[4466]: address: '128.242.245.29', Oct 26 15:22:37 smsl-d400n volumio[4466]: port: 443 Oct 26 15:22:37 smsl-d400n volumio[4466]: } Oct 26 15:22:37 smsl-d400n volumio[4466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:22:38 smsl-d400n sudo[6992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 15:21 Oct 26 15:22:38 smsl-d400n sudo[6992]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e71179fefb92227f783fc914e012504f08dcf6fb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Oct 2024 09:45:35 AM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="c83b50df238823988635e61d61230ef1"