-- Logs begin at Wed 2025-05-21 13:46:23 CST, end at Wed 2025-05-21 13:50:49 CST. -- May 21 13:49:01 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume55 May 21 13:49:01 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:01 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:01 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:01 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:01 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:01 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:01 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:01 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:01 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:02 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume46 May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:02 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:02 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:02 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:02 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:02 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume61 May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:02 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:03 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:03 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:03 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:03 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:03 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:03 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:04 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume92 May 21 13:49:04 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:04 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:04 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:04 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:04 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:04 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:04 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:04 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:04 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:05 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume100 May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:05 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:05 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:05 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume+ May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:05 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:05 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:05 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:06 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume+ May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:06 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume+ May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:06 smsl-n100 volumio[1078]: info: VolumeController::SetAlsaVolume+ May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:06 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:06 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:18 smsl-n100 volumio[1078]: info: May 21 13:49:18 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:18 smsl-n100 volumio[1078]: info: May 21 13:49:18 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:18 smsl-n100 volumio[1078]: info: May 21 13:49:18 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand status took 14 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand status took 10 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand status took 6 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:18 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:18 smsl-n100 volumio[1078]: 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":"\"Th3 Awak3n1ng\"","artist":"Ivan Torrent","album":"\"Th3 Awak3n1ng\"","uri":"http://m801.music.126.net/20250521141326/edd6456a1e0a3b4e915c047cdb3e4112/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1969261939/ca76/f178/8249/b5e2ba1af39ace1350f45a8accfd9f2f.mp3?vuutv=2SMG9eePKkhxu/pieIR15T/jTIWnywDtXYI/Vh6v0itFLyVbSzepjHBi0A7R+XlUOfSbdlX0TO44p7jU6Rm2gt8Ol7amiBWblAONkEnk0DA=","trackType":""} May 21 13:49:18 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::stPlaybackTimer May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:18 smsl-n100 volumio[1078]: 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":"\"Th3 Awak3n1ng\"","artist":"Ivan Torrent","album":"\"Th3 Awak3n1ng\"","uri":"http://m801.music.126.net/20250521141326/edd6456a1e0a3b4e915c047cdb3e4112/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1969261939/ca76/f178/8249/b5e2ba1af39ace1350f45a8accfd9f2f.mp3?vuutv=2SMG9eePKkhxu/pieIR15T/jTIWnywDtXYI/Vh6v0itFLyVbSzepjHBi0A7R+XlUOfSbdlX0TO44p7jU6Rm2gt8Ol7amiBWblAONkEnk0DA=","trackType":""} May 21 13:49:18 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: No code May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:18 smsl-n100 volumio[1078]: 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":"\"Th3 Awak3n1ng\"","artist":"Ivan Torrent","album":"\"Th3 Awak3n1ng\"","uri":"http://m801.music.126.net/20250521141326/edd6456a1e0a3b4e915c047cdb3e4112/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1969261939/ca76/f178/8249/b5e2ba1af39ace1350f45a8accfd9f2f.mp3?vuutv=2SMG9eePKkhxu/pieIR15T/jTIWnywDtXYI/Vh6v0itFLyVbSzepjHBi0A7R+XlUOfSbdlX0TO44p7jU6Rm2gt8Ol7amiBWblAONkEnk0DA=","trackType":""} May 21 13:49:18 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: No code May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:18 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:18 smsl-n100 volumio[1078]: info: ------------------------------ 268ms May 21 13:49:18 smsl-n100 volumio[1078]: info: ------------------------------ 264ms May 21 13:49:18 smsl-n100 volumio[1078]: info: ------------------------------ 261ms May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:18 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:18 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:18 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:18 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:18 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:18 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:19 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:19 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:19 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:19 smsl-n100 volumio[1078]: info: Updating RAAT Signal Path May 21 13:49:19 smsl-n100 volumio[1078]: info: Clearing queue after UPNP request May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:19 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:19 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:19 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:19 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:49:19 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:19 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreStateMachine::ClearQueue May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreStateMachine::stop May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:49:19 smsl-n100 volumio[1078]: info: CorePlayQueue::clearPlayQueue May 21 13:49:19 smsl-n100 volumio[1078]: info: CorePlayQueue::saveQueue May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:19 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:19 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushQueue May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:19 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:19 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 15ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:19 smsl-n100 volumio[1078]: info: Starting UPNP Playback May 21 13:49:19 smsl-n100 volumio[1078]: info: Preparing playback through UPNP May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetState May 21 13:49:19 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:19 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 11ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 15ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: May 21 13:49:19 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:19 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:49:19 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 6ms May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:20 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:20 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:20 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 5ms May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 16 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 23 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 18 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 12 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:20 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:20 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 48ms May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: May 21 13:49:20 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 36 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 30 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 30 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 17 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand status took 13 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:20 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:20 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:20 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:20 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:20 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:20 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 205ms May 21 13:49:20 smsl-n100 volumio[1078]: info: ------------------------------ 203ms May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 145 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 142 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 141 milliseconds May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:20 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:20 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:20 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:20 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:20 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:20 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:20 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:20 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:20 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:20 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:21 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:21 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:21 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:21 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:21 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:21 smsl-n100 volumio[1078]: info: ------------------------------ 493ms May 21 13:49:21 smsl-n100 volumio[1078]: info: ------------------------------ 475ms May 21 13:49:21 smsl-n100 volumio[1078]: info: ------------------------------ 470ms May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:21 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:21 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:21 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:49:21 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:21 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:21 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:30 smsl-n100 volumio[1078]: info: May 21 13:49:30 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:30 smsl-n100 volumio[1078]: info: May 21 13:49:30 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:30 smsl-n100 volumio[1078]: info: May 21 13:49:30 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand status took 16 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand status took 12 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand status took 8 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 11 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 11 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 11 milliseconds May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:30 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:30 smsl-n100 volumio[1078]: 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":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:30 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::stPlaybackTimer May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:30 smsl-n100 volumio[1078]: 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":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:30 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: No code May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:30 smsl-n100 volumio[1078]: 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":"Archangel","artist":"Two Steps From Hell","album":"Nero","uri":"http://m7.music.126.net/20250521141418/8b2301dc45f00de71026a5a9579e1fca/ymusic/cf7c/274c/0905/ed1462a09807f7a4f8bea90bd5d4cfd9.mp3?vuutv=XgEAxGCuXY45fSoQGPg9NWCwBL2qk66gCmgujg3kCY9N6JIS81+jeEwgTT7f53jcyjPyw+VXa4FLGJGmSmWWkEk+zT9uWT/p3Cf39ywQcaQ=","trackType":""} May 21 13:49:30 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:30 smsl-n100 volumio[1078]: info: No code May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:30 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:30 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 235ms May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 232ms May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 230ms May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: Clearing queue after UPNP request May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:31 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:31 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:31 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:31 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:49:31 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:31 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::ClearQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::stop May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:49:31 smsl-n100 volumio[1078]: info: CorePlayQueue::clearPlayQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: CorePlayQueue::saveQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:31 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:31 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushQueue May 21 13:49:31 smsl-n100 volumio[1078]: info: May 21 13:49:31 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:31 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:31 smsl-n100 volumio[1078]: info: May 21 13:49:31 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:31 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:31 smsl-n100 volumio[1078]: info: May 21 13:49:31 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:31 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:31 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:31 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:31 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 15ms May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 11ms May 21 13:49:31 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:49:31 smsl-n100 volumio[1078]: info: Starting UPNP Playback May 21 13:49:31 smsl-n100 volumio[1078]: info: Preparing playback through UPNP May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetState May 21 13:49:31 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:49:31 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 17ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 11ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 6ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: May 21 13:49:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:49:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:49:32 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 20 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 16 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: May 21 13:49:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 66 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 60 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 41 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 40 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 35 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 28 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 187ms May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 236ms May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 143 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 141 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 137 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 135 milliseconds May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:49:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:49:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":233,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:49:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:49:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 485ms May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 481ms May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 447ms May 21 13:49:33 smsl-n100 volumio[1078]: info: ------------------------------ 441ms May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:49:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:49:33 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:33 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:49:33 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:49:33 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:49:33 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:13 smsl-n100 volumio[1078]: info: May 21 13:50:13 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:13 smsl-n100 volumio[1078]: info: May 21 13:50:13 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:13 smsl-n100 volumio[1078]: info: May 21 13:50:13 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand status took 13 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand status took 14 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand status took 11 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:13 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:13 smsl-n100 volumio[1078]: 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":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:50:13 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::stPlaybackTimer May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:13 smsl-n100 volumio[1078]: 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":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:50:13 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: No code May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:13 smsl-n100 volumio[1078]: 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":"사랑이 말을 듣지않아","artist":"金在宇/유신","album":"헤어진 두사람","uri":"http://m701.music.126.net/20250521141430/fedb15fca9c3c2bb34691aaa962ddcee/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32047833845/8a7b/18ca/dc72/59ce82a7ef23c25fcc2e955bb778049c.mp3?vuutv=UW0K1ftllbX5is97v7o5CMIM0RWVPCma3odFjJy0BwHA/OkyqnrQEvxrDSFlw1CU4OvcvpQf8aXDX4qc8tsmy+W8ETry55PYezgvouX722E=","trackType":""} May 21 13:50:13 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: No code May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: info: ------------------------------ 215ms May 21 13:50:13 smsl-n100 volumio[1078]: info: ------------------------------ 212ms May 21 13:50:13 smsl-n100 volumio[1078]: info: ------------------------------ 210ms May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:13 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:13 smsl-n100 volumio[1078]: info: Clearing queue after UPNP request May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:13 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:13 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:13 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:13 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:50:13 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:13 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreStateMachine::ClearQueue May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreStateMachine::stop May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:50:14 smsl-n100 volumio[1078]: info: CorePlayQueue::clearPlayQueue May 21 13:50:14 smsl-n100 volumio[1078]: info: CorePlayQueue::saveQueue May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:14 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:14 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushQueue May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:14 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:14 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:14 smsl-n100 volumio[1078]: info: Starting UPNP Playback May 21 13:50:14 smsl-n100 volumio[1078]: info: Preparing playback through UPNP May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetState May 21 13:50:14 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:14 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 14ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 6ms May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 16ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: May 21 13:50:14 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:14 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 16ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:14 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 22 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 28 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 24 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: May 21 13:50:15 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 65 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 29 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 27 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 25 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 18 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand status took 12 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:15 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:15 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 207ms May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 240ms May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 234ms May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 140 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 134 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 132 milliseconds May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:15 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:15 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:15 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:15 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":163,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:15 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:15 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 352ms May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 291ms May 21 13:50:15 smsl-n100 volumio[1078]: info: ------------------------------ 286ms May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:15 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:15 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:15 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:50:15 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:15 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:15 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand status took 12 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand status took 8 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand status took 5 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 20 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 20 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 21 milliseconds May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:32 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:32 smsl-n100 volumio[1078]: 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":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:32 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::stPlaybackTimer May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:32 smsl-n100 volumio[1078]: 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":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:32 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: No code May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:32 smsl-n100 volumio[1078]: 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":"The Court","artist":"Adrián Berenguer","album":"Still Life","uri":"http://m801.music.126.net/20250521141513/7d1760630410791581b47b1203b9c629/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/33982279074/94cb/0c6e/74ce/4d203238b1f1aa02328c58b2e8108b1c.mp3?vuutv=76qXyfFleA/r3B/arZKEFOXg1G5BaxhsTCvWGZ85RrBC5YJi3zy4sfwfXGURHXBPVw8BogCIJzTKq9kUH8pL6QI6Ux7wBp3Kv11abJ0EzbQ=","trackType":""} May 21 13:50:32 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: No code May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 216ms May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 214ms May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 213ms May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: Clearing queue after UPNP request May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:32 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:50:32 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:32 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::ClearQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::stop May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::clearPlayQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::saveQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:32 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushQueue May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:32 smsl-n100 volumio[1078]: info: May 21 13:50:32 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:32 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:32 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:32 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:32 smsl-n100 volumio[1078]: error: updateQueue error: null May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 11ms May 21 13:50:32 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:32 smsl-n100 volumio[1078]: info: Starting UPNP Playback May 21 13:50:32 smsl-n100 volumio[1078]: info: Preparing playback through UPNP May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetState May 21 13:50:32 smsl-n100 volumio[1078]: info: CorePlayQueue::getTrack 0 May 21 13:50:32 smsl-n100 volumio[1078]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 12ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 7ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 11ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 13ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 9ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 6ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 10ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 5ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 5ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces system playlist update May 21 13:50:33 smsl-n100 volumio[1078]: info: Ignoring MPD Status Update May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 8ms May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 5ms May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 27 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 22 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 15 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: May 21 13:50:33 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 55 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 49 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 46 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 44 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 36 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: info: sendMpdCommand status took 16 milliseconds May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus stop May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:33 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:33 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:33 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:33 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:33 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:33 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:33 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:33 smsl-n100 volumio[1078]: info: ------------------------------ 246ms May 21 13:50:34 smsl-n100 volumio[1078]: info: ------------------------------ 266ms May 21 13:50:34 smsl-n100 volumio[1078]: info: ------------------------------ 256ms May 21 13:50:34 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 176 milliseconds May 21 13:50:34 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 168 milliseconds May 21 13:50:34 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 167 milliseconds May 21 13:50:34 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:34 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:34 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:34 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:34 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:34 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:34 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:34 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:34 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:34 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:34 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:34 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:34 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:34 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:34 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:34 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: info: ------------------------------ 373ms May 21 13:50:34 smsl-n100 volumio[1078]: info: ------------------------------ 346ms May 21 13:50:34 smsl-n100 volumio[1078]: info: ------------------------------ 332ms May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:34 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:34 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:34 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 13:50:34 smsl-n100 volumio[1078]: error: Serial API: Failed to decode command: 100, message: undefined May 21 13:50:34 smsl-n100 volumio[1078]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:34 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: May 21 13:50:40 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:40 smsl-n100 volumio[1078]: info: May 21 13:50:40 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:40 smsl-n100 volumio[1078]: info: May 21 13:50:40 smsl-n100 volumio[1078]: ---------------------------- MPD announces state update: player May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::getState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand status May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand status took 16 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand status took 11 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand status took 6 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:40 smsl-n100 volumio[1078]: verbose: ControllerMpd::parseTrackInfo May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:40 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80000,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:40 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:40 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:40 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80000,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:40 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:40 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: ControllerMpd::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::servicePushState May 21 13:50:40 smsl-n100 volumio[1078]: verbose: In UPNP mode May 21 13:50:40 smsl-n100 volumio[1078]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80982,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Europa","artist":"Globus","album":"Epicon (UK Version)","uri":"http://m7.music.126.net/20250521141532/3a8f5fe66edc066b5c0c1dbcd902e79d/ymusic/050b/0158/030f/2a541d3fc66209c5d4c54e4177fb1805.mp3?vuutv=D5Glm3w3Krpvlgne0Z0WDA3GTCxiK1Soa7aSROgUslHeXquIORmYbVfYMdadU5S6I0ogcCNrhdP/B6mqt4wtXrjIUFEf5gjBAFSXdfSFYmk=","trackType":""} May 21 13:50:40 smsl-n100 volumio[1078]: verbose: CURRENT POSITION 0 May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState stateService play May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::syncState currentStatus play May 21 13:50:40 smsl-n100 volumio[1078]: info: Received an update from plugin. extracting info from payload May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::pushState May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioPushState May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output update for this device May 21 13:50:40 smsl-n100 volumio[1078]: info: MRS: Pushing multiroomSync output May 21 13:50:40 smsl-n100 volumio[1078]: info: ------------------------------ 218ms May 21 13:50:40 smsl-n100 volumio[1078]: info: ------------------------------ 189ms May 21 13:50:40 smsl-n100 volumio[1078]: info: ------------------------------ 186ms May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreCommandRouter::volumioGetQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CoreStateMachine::getQueue May 21 13:50:40 smsl-n100 volumio[1078]: info: CorePlayQueue::getQueue May 21 13:50:48 smsl-n100 volumio[1078]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:50:48 smsl-n100 volumio[1078]: Error: connect ETIMEDOUT 31.13.70.33:443 May 21 13:50:48 smsl-n100 volumio[1078]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 13:50:48 smsl-n100 volumio[1078]: errno: -110, May 21 13:50:48 smsl-n100 volumio[1078]: code: 'ETIMEDOUT', May 21 13:50:48 smsl-n100 volumio[1078]: syscall: 'connect', May 21 13:50:48 smsl-n100 volumio[1078]: address: '31.13.70.33', May 21 13:50:48 smsl-n100 volumio[1078]: port: 443 May 21 13:50:48 smsl-n100 volumio[1078]: } May 21 13:50:48 smsl-n100 volumio[1078]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:50:49 smsl-n100 sudo[2574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 13:49 May 21 13:50:49 smsl-n100 sudo[2574]: 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"