-- Logs begin at Wed 2025-05-21 13:16:11 CST, end at Wed 2025-05-21 13:29:05 CST. -- May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioNext May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::next May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::next May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand next May 21 13:28:06 smsl-n100 volumio[7225]: info: sendMpdCommand next took 7 milliseconds May 21 13:28:06 smsl-n100 volumio[7225]: info: May 21 13:28:06 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:06 smsl-n100 volumio[7225]: info: May 21 13:28:06 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:06 smsl-n100 volumio[7225]: info: May 21 13:28:06 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:06 smsl-n100 volumio[7225]: info: sendMpdCommand status took 11 milliseconds May 21 13:28:06 smsl-n100 volumio[7225]: info: sendMpdCommand status took 9 milliseconds May 21 13:28:06 smsl-n100 volumio[7225]: info: sendMpdCommand status took 5 milliseconds May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:06 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:06 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:06 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::stPlaybackTimer May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:06 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:06 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: No code May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:06 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:06 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: No code May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: ------------------------------ 173ms May 21 13:28:06 smsl-n100 volumio[7225]: info: ------------------------------ 171ms May 21 13:28:06 smsl-n100 volumio[7225]: info: ------------------------------ 170ms May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:06 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: VolumeController::SetAlsaVolume50 May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:06 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:06 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:06 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:06 smsl-n100 volumio[7225]: info: VolumeController::SetAlsaVolume0 May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:06 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:06 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:06 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:11 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioNext May 21 13:28:11 smsl-n100 volumio[7225]: info: CoreStateMachine::next May 21 13:28:11 smsl-n100 volumio[7225]: info: ControllerMpd::next May 21 13:28:11 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand next May 21 13:28:13 smsl-n100 ntpd[949]: 193.182.111.142 local addr 192.168.31.222 -> May 21 13:28:16 smsl-n100 volumio[7225]: info: Clearing queue after UPNP request May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreStateMachine::ClearQueue May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreStateMachine::stop May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:28:16 smsl-n100 volumio[7225]: info: CorePlayQueue::clearPlayQueue May 21 13:28:16 smsl-n100 volumio[7225]: info: CorePlayQueue::saveQueue May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:16 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:16 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushQueue May 21 13:28:16 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:16 smsl-n100 volumio[7225]: info: May 21 13:28:16 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:16 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:16 smsl-n100 volumio[7225]: info: May 21 13:28:16 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:16 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:16 smsl-n100 volumio[7225]: info: May 21 13:28:16 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:16 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:16 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:16 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:16 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:16 smsl-n100 volumio[7225]: info: ------------------------------ 20ms May 21 13:28:16 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:16 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:16 smsl-n100 volumio[7225]: info: Starting UPNP Playback May 21 13:28:16 smsl-n100 volumio[7225]: info: Preparing playback through UPNP May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:16 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:16 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 4ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 4ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:17 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 5ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 24 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 30 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 25 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 11 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 10 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:17 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:17 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 68ms May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: info: May 21 13:28:17 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 40 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 38 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 17 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand status took 11 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:17 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:17 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:17 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:17 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:17 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:17 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:17 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:17 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:17 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 277ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 275ms May 21 13:28:17 smsl-n100 volumio[7225]: info: ------------------------------ 264ms May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 184 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 182 milliseconds May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:17 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:17 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:17 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:17 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:17 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:17 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:17 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:18 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:18 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135316/d3c2e48ce873043f6839e5d48c0921f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=3MTPnpS20jxGfZD2ktkABeKPoADJTDWwqMqsEiqKvfEizhO6Gbif0BRDMp8HOTxPEo24qyr7WGwO/+kVdCl+pHMpGLMpbi8WQGdj5i//oSo=","trackType":""} May 21 13:28:18 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:18 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 303ms May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 298ms May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:18 smsl-n100 volumio[7225]: info: Clearing queue after UPNP request May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:18 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::ClearQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::stPlaybackTimer May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::updateTrackBlock May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrackBlock May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::serviceStop May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::stop May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::clearPlayQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::saveQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushQueue May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:18 smsl-n100 volumio[7225]: info: sendMpdCommand stop took 63 milliseconds May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:18 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:18 smsl-n100 volumio[7225]: info: sendMpdCommand status took 5 milliseconds May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:18 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: No code May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 76ms May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:18 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 85ms May 21 13:28:18 smsl-n100 volumio[7225]: info: sendMpdCommand status took 11 milliseconds May 21 13:28:18 smsl-n100 volumio[7225]: info: sendMpdCommand status took 8 milliseconds May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:18 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:18 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: No code May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:18 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: No code May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 127ms May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 125ms May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:18 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:18 smsl-n100 volumio[7225]: info: May 21 13:28:18 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:18 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:18 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:18 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 89ms May 21 13:28:18 smsl-n100 volumio[7225]: info: ------------------------------ 87ms May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:18 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:18 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:18 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:18 smsl-n100 volumio[7225]: info: Starting UPNP Playback May 21 13:28:18 smsl-n100 volumio[7225]: info: Preparing playback through UPNP May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:18 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:18 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 17ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 12ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:19 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 12ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 27 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 22 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 18 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 30 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 27 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 17 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:19 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:19 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:19 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:19 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:19 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:19 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:19 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:19 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 170ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 228ms May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 223ms May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: May 21 13:28:19 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 186 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 12 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: info: sendMpdCommand status took 8 milliseconds May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:19 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:19 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:19 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:19 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:19 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:19 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:19 smsl-n100 volumio[7225]: info: ------------------------------ 292ms May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:19 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 155 milliseconds May 21 13:28:20 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 153 milliseconds May 21 13:28:20 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:20 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:20 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:20 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:20 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:20 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:20 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:20 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:20 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:20 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:20 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:20 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:20 smsl-n100 volumio[7225]: info: ------------------------------ 270ms May 21 13:28:20 smsl-n100 volumio[7225]: info: ------------------------------ 264ms May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:20 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:20 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:20 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:20 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:20 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:20 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:24 smsl-n100 volumio[7225]: info: May 21 13:28:24 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:24 smsl-n100 volumio[7225]: info: May 21 13:28:24 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:24 smsl-n100 volumio[7225]: info: May 21 13:28:24 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand status took 11 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand status took 8 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand status took 5 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:24 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:24 smsl-n100 volumio[7225]: 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":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:24 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::stPlaybackTimer May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:24 smsl-n100 volumio[7225]: 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":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:24 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: No code May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:24 smsl-n100 volumio[7225]: 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":"Revolutions","artist":"Capo Productions","album":"Music Dreams","uri":"http://m701.music.126.net/20250521135317/b8ec16033902d739b424c91061b5a0ce/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/59334306056/8da3/44e0/27a2/0fd880b34d04d67e546463b07d2874b5.mp3?vuutv=a8lggBkR28ZrR6s2bek7pOUIxXf0BgdmvTolRlnaqAzbi8OlGUc5VGqUPPzCBniVS9bsYb+EoyE8l8FpjCn4hT9dgZFwdOPseV4Fq9yY8Rg=","trackType":""} May 21 13:28:24 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: No code May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:24 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:24 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:24 smsl-n100 volumio[7225]: info: ------------------------------ 148ms May 21 13:28:24 smsl-n100 volumio[7225]: info: ------------------------------ 146ms May 21 13:28:24 smsl-n100 volumio[7225]: info: ------------------------------ 146ms May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: Clearing queue after UPNP request May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:25 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:25 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:25 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:25 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:25 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:25 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::ClearQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::stop May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:28:25 smsl-n100 volumio[7225]: info: CorePlayQueue::clearPlayQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: CorePlayQueue::saveQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:25 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:25 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushQueue May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:25 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:25 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 14ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:25 smsl-n100 volumio[7225]: info: Starting UPNP Playback May 21 13:28:25 smsl-n100 volumio[7225]: info: Preparing playback through UPNP May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:25 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:25 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 14ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 14ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: May 21 13:28:25 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:25 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 15ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:25 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: May 21 13:28:26 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:26 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:26 smsl-n100 volumio[7225]: info: ------------------------------ 5ms May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: May 21 13:28:27 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 39 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 36 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 25 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 22 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 13 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand status took 29 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 23 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 20 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:27 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 135ms May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 168ms May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 122 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 120 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 119 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 103 milliseconds May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:27 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:27 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:27 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:27 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:27 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135324/41ddf316bbdb7db43ba76fb97e83c5cf/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=ekzLzXed/y7qhbNlAq+VUYJZdWcJh9+tIpPSyp0TSN7L0a+e7Yvq3Vyxt2TOt5JQ3gyRDVEKCM6KBzW3k1Zhr0ZeOG5jzsTyI/c1HL4GW5s=","trackType":""} May 21 13:28:27 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:27 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 359ms May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 356ms May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 352ms May 21 13:28:27 smsl-n100 volumio[7225]: info: ------------------------------ 344ms May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:27 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:27 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:27 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:27 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:27 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:27 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:39 smsl-n100 volumio[7225]: info: Clearing queue after UPNP request May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::ClearQueue May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::stop May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::stPlaybackTimer May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::updateTrackBlock May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrackBlock May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::serviceStop May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:39 smsl-n100 volumio[7225]: info: ControllerMpd::stop May 21 13:28:39 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::clearPlayQueue May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::saveQueue May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushQueue May 21 13:28:39 smsl-n100 volumio[7225]: info: May 21 13:28:39 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:39 smsl-n100 volumio[7225]: info: sendMpdCommand stop took 57 milliseconds May 21 13:28:39 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:39 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:39 smsl-n100 volumio[7225]: info: May 21 13:28:39 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:39 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:39 smsl-n100 volumio[7225]: info: sendMpdCommand status took 5 milliseconds May 21 13:28:39 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:39 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:39 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:39 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:39 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:39 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:39 smsl-n100 volumio[7225]: info: No code May 21 13:28:39 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 68ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:40 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:40 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:40 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:40 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:40 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 78ms May 21 13:28:40 smsl-n100 volumio[7225]: info: sendMpdCommand status took 10 milliseconds May 21 13:28:40 smsl-n100 volumio[7225]: info: sendMpdCommand status took 7 milliseconds May 21 13:28:40 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:40 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:40 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:40 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: No code May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:40 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: No code May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 134ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 132ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:40 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 77ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 75ms May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:40 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:40 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:40 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:40 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:40 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:40 smsl-n100 volumio[7225]: info: Starting UPNP Playback May 21 13:28:40 smsl-n100 volumio[7225]: info: Preparing playback through UPNP May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:40 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:40 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 14ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 12ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 17ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 15ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 16ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 14ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 10ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 8ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:40 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 11ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:40 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:40 smsl-n100 volumio[7225]: info: May 21 13:28:40 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 14 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 22 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 19 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 12 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 57ms May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 48 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 39 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 38 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 20 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 15 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 252ms May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 249ms May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 177 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 171 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 171 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 373ms May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 344ms May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 338ms May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:41 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:41 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:41 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: May 21 13:28:41 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 13 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 8 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand status took 6 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 13 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 12 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 10 milliseconds May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13000,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13000,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:41 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:41 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13000,"duration":120,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"奇襲","artist":"高梨康治","album":"TVアニメ「織田信奈の野望」オリジナルサウンドトラックアルバム","uri":"http://m7.music.126.net/20250521135339/9dbb6ab025c98b2a0220cabed415a070/ymusic/1289/c54f/307d/aa96465ae519e219d59eb980c123a6dc.mp3?vuutv=KXhtL46v8YSfGi4asvvpypWNko3NX9Fk2c8X7/4wV3GWpgbDb/yigYCtKMpfdegPtjLV8t24RW9ik4G7zr2tJ4o7D4lXA9usre/oSzZsN6A=","trackType":""} May 21 13:28:41 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:41 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:41 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 197ms May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 191ms May 21 13:28:41 smsl-n100 volumio[7225]: info: ------------------------------ 188ms May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:41 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:42 smsl-n100 volumio[7225]: info: Clearing queue after UPNP request May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::ClearQueue May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::stPlaybackTimer May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::updateTrackBlock May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrackBlock May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::serviceStop May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::stop May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::clearPlayQueue May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::saveQueue May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushQueue May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:42 smsl-n100 volumio[7225]: info: sendMpdCommand stop took 53 milliseconds May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:42 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:42 smsl-n100 volumio[7225]: info: sendMpdCommand status took 5 milliseconds May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:42 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: No code May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 53ms May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:42 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 60ms May 21 13:28:42 smsl-n100 volumio[7225]: info: sendMpdCommand status took 9 milliseconds May 21 13:28:42 smsl-n100 volumio[7225]: info: sendMpdCommand status took 6 milliseconds May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:42 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:42 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: No code May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 21 13:28:42 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: No code May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 97ms May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 96ms May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:42 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:42 smsl-n100 volumio[7225]: info: May 21 13:28:42 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:42 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:42 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:42 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:42 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:42 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:42 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:42 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:42 smsl-n100 volumio[7225]: error: updateQueue error: null May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 106ms May 21 13:28:42 smsl-n100 volumio[7225]: info: ------------------------------ 105ms May 21 13:28:42 smsl-n100 volumio[7225]: info: Starting UPNP Playback May 21 13:28:42 smsl-n100 volumio[7225]: info: Preparing playback through UPNP May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:42 smsl-n100 volumio[7225]: info: CorePlayQueue::getTrack 0 May 21 13:28:42 smsl-n100 volumio[7225]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 13ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 5ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 9ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 6ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces system playlist update May 21 13:28:43 smsl-n100 volumio[7225]: info: Ignoring MPD Status Update May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 7ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 5ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 19 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 27 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 22 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 13 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus stop May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 64ms May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: May 21 13:28:43 smsl-n100 volumio[7225]: ---------------------------- MPD announces state update: player May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::getState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand status May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 54 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 45 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 44 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 25 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand status took 13 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:43 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:43 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 271ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 267ms May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 184 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 180 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: info: sendMpdCommand playlistinfo took 179 milliseconds May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: verbose: ControllerMpd::parseTrackInfo May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:43 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:43 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: ControllerMpd::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::servicePushState May 21 13:28:43 smsl-n100 volumio[7225]: verbose: In UPNP mode May 21 13:28:43 smsl-n100 volumio[7225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2M-2","artist":"川井憲次","album":"Kenji Kawai Original Masters vol.2 ~よみがえる第二次世界大戦~ APOCALYPSE","uri":"http://m7.music.126.net/20250521135341/011a18e775efdea3aceda517081d4f36/ymusic/a92a/dda3/82f0/a28dcabd37d200fca2a45f1c6786fc3f.mp3?vuutv=kn9MGB+80cv+dPUDusgI40Mk2NccVx5tx/Fu0T8IsSrKvrQHXWmoxhtgpOBH7dM07fl+PMnQ7BJfnRs+WCkctXFyZ01mCaPiH4XUK6ppoPc=","trackType":""} May 21 13:28:43 smsl-n100 volumio[7225]: verbose: CURRENT POSITION 0 May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState stateService play May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::syncState currentStatus play May 21 13:28:43 smsl-n100 volumio[7225]: info: Received an update from plugin. extracting info from payload May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 393ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 361ms May 21 13:28:43 smsl-n100 volumio[7225]: info: ------------------------------ 351ms May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:43 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:43 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:43 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:28:43 smsl-n100 volumio[7225]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:28:43 smsl-n100 volumio[7225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:43 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:51 smsl-n100 volumio[7225]: verbose: New Socket.io Connection to 192.168.31.222 from 192.168.31.76 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 13:28:51 smsl-n100 volumio[7225]: verbose: New Socket.io Connection to 192.168.31.222 from 192.168.31.76 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetVisibleSources May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 13:28:51 smsl-n100 volumio[7225]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 21 13:28:51 smsl-n100 volumio[7225]: info: Received Get System Info May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:28:51 smsl-n100 volumio[7225]: info: Discovery: Getting this device information May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:28:51 smsl-n100 volumio[7225]: info: Listing playlists May 21 13:28:51 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 13:28:55 smsl-n100 volumio[7225]: info: VolumeController::SetAlsaVolume10 May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:55 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:55 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:28:55 smsl-n100 volumio[7225]: info: VolumeController::SetAlsaVolume10 May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreStateMachine::pushState May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioPushState May 21 13:28:55 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output update for this device May 21 13:28:55 smsl-n100 volumio[7225]: info: MRS: Pushing multiroomSync output May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: CoreStateMachine::getQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: CorePlayQueue::getQueue May 21 13:28:55 smsl-n100 volumio[7225]: info: Updating RAAT Signal Path May 21 13:29:00 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:29:00 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:29:00 smsl-n100 volumio[7225]: info: Discovery: Getting this device information May 21 13:29:00 smsl-n100 volumio[7225]: info: CoreCommandRouter::volumioGetState May 21 13:29:00 smsl-n100 volumio[7225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:29:04 smsl-n100 volumio[7225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:29:04 smsl-n100 volumio[7225]: Error: connect ETIMEDOUT 199.16.156.40:443 May 21 13:29:04 smsl-n100 volumio[7225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 13:29:04 smsl-n100 volumio[7225]: errno: -110, May 21 13:29:04 smsl-n100 volumio[7225]: code: 'ETIMEDOUT', May 21 13:29:04 smsl-n100 volumio[7225]: syscall: 'connect', May 21 13:29:04 smsl-n100 volumio[7225]: address: '199.16.156.40', May 21 13:29:04 smsl-n100 volumio[7225]: port: 443 May 21 13:29:04 smsl-n100 volumio[7225]: } May 21 13:29:04 smsl-n100 volumio[7225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:29:05 smsl-n100 sudo[10581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 13:28 May 21 13:29:05 smsl-n100 sudo[10581]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"