-- Logs begin at Wed 2025-05-21 23:46:30 HKT, end at Wed 2025-05-21 23:53:35 HKT. -- May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand status took 11 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand status took 7 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand status took 4 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:00 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:00 smsl-n100 volumio[791]: 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":"Righteous Path","artist":"Blazo","album":"Introducing Mellow","uri":"http://m801.music.126.net/20250522001655/f51c7dbe8165438334431f50dc197a6d/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1559515192/3c59/8804/d3a0/a4198c38a65ce29d0424442f3c1a5f98.mp3?vuutv=JAC9+7BNbAwaO2Qvtt8dXPdj9ZYLzVUfwq9xQcYeHurKvoXhWMohMgwi2uY23mvGKWy+q3PbDewd6GoKS8yO2cUA7HfYoFlwrkHnPhLc5/0=","trackType":""} May 21 23:52:00 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::stPlaybackTimer May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:00 smsl-n100 volumio[791]: 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":"Righteous Path","artist":"Blazo","album":"Introducing Mellow","uri":"http://m801.music.126.net/20250522001655/f51c7dbe8165438334431f50dc197a6d/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1559515192/3c59/8804/d3a0/a4198c38a65ce29d0424442f3c1a5f98.mp3?vuutv=JAC9+7BNbAwaO2Qvtt8dXPdj9ZYLzVUfwq9xQcYeHurKvoXhWMohMgwi2uY23mvGKWy+q3PbDewd6GoKS8yO2cUA7HfYoFlwrkHnPhLc5/0=","trackType":""} May 21 23:52:00 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: No code May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:00 smsl-n100 volumio[791]: 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":"Righteous Path","artist":"Blazo","album":"Introducing Mellow","uri":"http://m801.music.126.net/20250522001655/f51c7dbe8165438334431f50dc197a6d/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1559515192/3c59/8804/d3a0/a4198c38a65ce29d0424442f3c1a5f98.mp3?vuutv=JAC9+7BNbAwaO2Qvtt8dXPdj9ZYLzVUfwq9xQcYeHurKvoXhWMohMgwi2uY23mvGKWy+q3PbDewd6GoKS8yO2cUA7HfYoFlwrkHnPhLc5/0=","trackType":""} May 21 23:52:00 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: No code May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 138ms May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 135ms May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 138ms May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:00 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:00 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:00 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:00 smsl-n100 volumio[791]: info: Clearing queue after UPNP request May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::ClearQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::stop May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::clearPlayQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::saveQueue May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:00 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushQueue May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:00 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:00 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:00 smsl-n100 volumio[791]: info: May 21 23:52:00 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:00 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:00 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:00 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:00 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:00 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:00 smsl-n100 volumio[791]: info: Starting UPNP Playback May 21 23:52:00 smsl-n100 volumio[791]: info: Preparing playback through UPNP May 21 23:52:00 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:00 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:00 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 16ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 14ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 4ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 4ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:01 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 10ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 15 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 12 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 8 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: May 21 23:52:01 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 27 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 25 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 23 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 23 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 16 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand status took 12 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:01 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:01 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 146ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 214ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 215ms May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 173 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 169 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 173 milliseconds May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:01 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:01 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:01 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:01 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:01 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:01 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:01 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 390ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 375ms May 21 23:52:01 smsl-n100 volumio[791]: info: ------------------------------ 371ms May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:01 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:01 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:01 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:01 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:01 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:01 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:01 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:03 smsl-n100 volumio[791]: info: May 21 23:52:03 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:03 smsl-n100 volumio[791]: info: May 21 23:52:03 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:03 smsl-n100 volumio[791]: info: May 21 23:52:03 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand status took 11 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand status took 7 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand status took 4 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:03 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:03 smsl-n100 volumio[791]: 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":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:03 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::stPlaybackTimer May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:03 smsl-n100 volumio[791]: 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":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:03 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: No code May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:03 smsl-n100 volumio[791]: 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":"Close Eyes","artist":"DVRST","album":"Close Eyes","uri":"http://m801.music.126.net/20250522001700/94a2d17ead146ac5250356233f8898ff/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/9147316333/2c16/a0f0/8134/95257fbf42497b63f16dae8e43053eb2.mp3?vuutv=mT9bXUurrNx3h26Wv6+JAQZAzKrtiXGo9QrN0M0KdhyLU+xMP5ipi8Lcxl0UeSAbcVYsv/ATP1NTqXbigkmPKfTiZh6EEYMB//08hfLq5js=","trackType":""} May 21 23:52:03 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: No code May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: ------------------------------ 145ms May 21 23:52:03 smsl-n100 volumio[791]: info: ------------------------------ 144ms May 21 23:52:03 smsl-n100 volumio[791]: info: ------------------------------ 143ms May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:03 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:03 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:03 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:03 smsl-n100 volumio[791]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DVRST/Close%20Eyes/9e16e2b2-0c8a-47e1-9aea-f8ad41a65b52.jpg' May 21 23:52:03 smsl-n100 volumio[791]: info: Clearing queue after UPNP request May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::ClearQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::stop May 21 23:52:03 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::clearPlayQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CorePlayQueue::saveQueue May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:03 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:03 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushQueue May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:04 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:04 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 10ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:04 smsl-n100 volumio[791]: info: Starting UPNP Playback May 21 23:52:04 smsl-n100 volumio[791]: info: Preparing playback through UPNP May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:04 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:04 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand status took 22 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand status took 18 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand status took 14 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand status took 20 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand status took 25 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 11 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 10 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:04 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:04 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:04 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:04 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:04 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:04 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:04 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 97ms May 21 23:52:04 smsl-n100 volumio[791]: info: ------------------------------ 133ms May 21 23:52:04 smsl-n100 volumio[791]: info: May 21 23:52:04 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 100 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 92 milliseconds May 21 23:52:04 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:04 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:04 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:04 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:04 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:04 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:04 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:04 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:04 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:04 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:04 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:04 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:04 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:05 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:05 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:05 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:05 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: ------------------------------ 366ms May 21 23:52:05 smsl-n100 volumio[791]: info: ------------------------------ 368ms May 21 23:52:05 smsl-n100 volumio[791]: info: ------------------------------ 367ms May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: sendMpdCommand status took 369 milliseconds May 21 23:52:05 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:05 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:05 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 62 milliseconds May 21 23:52:05 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:05 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:05 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:05 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":385,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:05 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:05 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: info: ------------------------------ 486ms May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:05 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:05 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:05 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:05 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:05 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:05 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:05 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:07 smsl-n100 volumio[791]: info: May 21 23:52:07 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:07 smsl-n100 volumio[791]: info: May 21 23:52:07 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:07 smsl-n100 volumio[791]: info: May 21 23:52:07 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand status took 10 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand status took 7 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand status took 9 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:07 smsl-n100 volumio[791]: 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":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:07 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::stPlaybackTimer May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:07 smsl-n100 volumio[791]: 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":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:07 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: No code May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: ------------------------------ 114ms May 21 23:52:07 smsl-n100 volumio[791]: info: ------------------------------ 113ms May 21 23:52:07 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 96 milliseconds May 21 23:52:07 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:07 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:07 smsl-n100 volumio[791]: 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":"Moonlight on Havasu Creek","artist":"Nicholas Gunn","album":"The Music of the Grand Canyon","uri":"http://m801.music.126.net/20250522001703/316ff14dfa033e5443af8d5dadfb58fb/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/18213189745/28c5/2a9a/7fbc/a4f12cc21ae9e6bafe0ff579c27a5ed3.mp3?vuutv=ECqeU1DNmIpJewk4cGnkh4RGJBfR6DBdGd3N3yqIEBEKJ6I30GkqxaUBM2rt+HERfx/1ri6t9ZZ0lFvT9iaIRXuG7XVntspUMbukNjXmC7A=","trackType":""} May 21 23:52:07 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: No code May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: ------------------------------ 157ms May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:07 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:07 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:07 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:07 smsl-n100 volumio[791]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nicholas%20Gunn/The%20Music%20of%20the%20Grand%20Canyon/5504bcc2-da36-47f9-ae32-9571b8f3d497.png' May 21 23:52:07 smsl-n100 volumio[791]: info: Clearing queue after UPNP request May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::ClearQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::stop May 21 23:52:07 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::clearPlayQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CorePlayQueue::saveQueue May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:07 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:07 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushQueue May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:08 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:08 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 10ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:08 smsl-n100 volumio[791]: info: Starting UPNP Playback May 21 23:52:08 smsl-n100 volumio[791]: info: Preparing playback through UPNP May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:08 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 10ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:08 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 16 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 13 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: May 21 23:52:08 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 31 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 25 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 23 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 21 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 16 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand status took 11 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:08 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:08 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:08 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:08 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:08 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:08 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:08 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 130ms May 21 23:52:08 smsl-n100 volumio[791]: info: ------------------------------ 164ms May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 118 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 117 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 100 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 100 milliseconds May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:08 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:08 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:08 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:08 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:08 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:08 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:08 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:08 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:08 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:08 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:08 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:08 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:08 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:09 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:09 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:09 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:09 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:09 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:09 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:09 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:09 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: info: ------------------------------ 384ms May 21 23:52:09 smsl-n100 volumio[791]: info: ------------------------------ 390ms May 21 23:52:09 smsl-n100 volumio[791]: info: ------------------------------ 380ms May 21 23:52:09 smsl-n100 volumio[791]: info: ------------------------------ 376ms May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:09 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:09 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:09 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:09 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:09 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:09 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:09 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:11 smsl-n100 volumio[791]: info: May 21 23:52:11 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:11 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:11 smsl-n100 volumio[791]: info: May 21 23:52:11 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:11 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:11 smsl-n100 volumio[791]: info: May 21 23:52:11 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:11 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand status took 11 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand status took 11 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand status took 8 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:11 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:11 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:11 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:11 smsl-n100 volumio[791]: 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":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:11 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::stPlaybackTimer May 21 23:52:11 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:11 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:11 smsl-n100 volumio[791]: 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":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:11 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:11 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:11 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:11 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:11 smsl-n100 volumio[791]: info: No code May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:12 smsl-n100 volumio[791]: 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":"Best Moments (feat. Kondor)","artist":"Blazo/Kondor","album":"Alone Journey","uri":"http://m701.music.126.net/20250522001707/e148e931cd5905ff05c5f2b11e1ed1d3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/31260417984/2c51/6337/d23c/1ec2ffdabb63aa7049ee9b85dcbd157b.mp3?vuutv=GZ6mNzNlnQ1wNcoP6fkgm5q4oN770VKwBVrmu29H7KM/ZwDSPjr/UXl2td9a/rHhEGbtzRKr2Khkval4ret2FggUN/dW9j/sYf2e1LGSVq4=","trackType":""} May 21 23:52:12 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: info: No code May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 154ms May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 151ms May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 149ms May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:12 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:12 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:12 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:12 smsl-n100 volumio[791]: info: Clearing queue after UPNP request May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::ClearQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::stop May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:52:12 smsl-n100 volumio[791]: info: CorePlayQueue::clearPlayQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CorePlayQueue::saveQueue May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:12 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushQueue May 21 23:52:12 smsl-n100 volumio[791]: info: May 21 23:52:12 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:12 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:12 smsl-n100 volumio[791]: info: May 21 23:52:12 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:12 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:12 smsl-n100 volumio[791]: info: May 21 23:52:12 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:12 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:12 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:12 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:12 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:12 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:12 smsl-n100 volumio[791]: info: Starting UPNP Playback May 21 23:52:12 smsl-n100 volumio[791]: info: Preparing playback through UPNP May 21 23:52:12 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:12 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:52:12 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:52:13 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 16 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 13 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: May 21 23:52:13 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 50 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 44 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 43 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 40 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 34 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand status took 20 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:13 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 164ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 200ms May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 126 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 126 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 122 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 121 milliseconds May 21 23:52:13 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:13 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:13 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:13 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:52:13 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:52:13 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"复刻回忆","artist":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:52:13 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:52:13 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 381ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 378ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 365ms May 21 23:52:13 smsl-n100 volumio[791]: info: ------------------------------ 353ms May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:52:13 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:52:13 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:13 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:52:13 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:52:13 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:52:13 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:52:13 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:52:25 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:52:25 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:52:25 smsl-n100 volumio[791]: info: Discovery: Getting this device information May 21 23:52:25 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:25 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:52:31 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:52:31 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:52:31 smsl-n100 volumio[791]: info: Discovery: Getting this device information May 21 23:52:31 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:52:31 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:53:15 smsl-n100 volumio[791]: info: May 21 23:53:15 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:15 smsl-n100 volumio[791]: info: May 21 23:53:15 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:15 smsl-n100 volumio[791]: info: May 21 23:53:15 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand status took 13 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand status took 8 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand status took 5 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:15 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:53:15 smsl-n100 volumio[791]: 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":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:53:15 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::stPlaybackTimer May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:53:15 smsl-n100 volumio[791]: 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":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:53:15 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: No code May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:53:15 smsl-n100 volumio[791]: 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":"Pianoboy高至豪","album":"复刻回忆","uri":"http://m701.music.126.net/20250522001712/fa3f5508db4ea5a08b69cb6add162c88/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1583406761/6350/e5f5/7d83/323d7fc20f6d190ada23e21811211f70.mp3?vuutv=d9NwtxBwb1zE1EKlDnd4Bc8UB7HoPF9G9aXlA+BSVonZqnY1/n4oqpkhfCUsC1QnbkyQnCJFHQmXOfw40TdUZLXwER/9T9MgYbNRKp+ZnkE=","trackType":""} May 21 23:53:15 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService stop May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: No code May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: info: ------------------------------ 145ms May 21 23:53:15 smsl-n100 volumio[791]: info: ------------------------------ 131ms May 21 23:53:15 smsl-n100 volumio[791]: info: ------------------------------ 132ms May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:15 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:15 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:15 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:15 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:15 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:53:15 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:53:15 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:53:15 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:53:16 smsl-n100 volumio[791]: info: Clearing queue after UPNP request May 21 23:53:16 smsl-n100 volumio[791]: info: CoreStateMachine::ClearQueue May 21 23:53:16 smsl-n100 volumio[791]: info: CoreStateMachine::stop May 21 23:53:16 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:53:16 smsl-n100 volumio[791]: info: CorePlayQueue::clearPlayQueue May 21 23:53:16 smsl-n100 volumio[791]: info: CorePlayQueue::saveQueue May 21 23:53:16 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:16 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:16 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:16 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushQueue May 21 23:53:16 smsl-n100 volumio[791]: info: May 21 23:53:16 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:16 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:16 smsl-n100 volumio[791]: info: May 21 23:53:16 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:16 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:16 smsl-n100 volumio[791]: info: May 21 23:53:16 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:16 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:16 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:53:16 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:53:16 smsl-n100 volumio[791]: error: updateQueue error: null May 21 23:53:16 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:53:16 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:53:16 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:53:16 smsl-n100 volumio[791]: info: Starting UPNP Playback May 21 23:53:16 smsl-n100 volumio[791]: info: Preparing playback through UPNP May 21 23:53:16 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetState May 21 23:53:16 smsl-n100 volumio[791]: info: CorePlayQueue::getTrack 0 May 21 23:53:16 smsl-n100 volumio[791]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 11ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 12ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 9ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 6ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 8ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces system playlist update May 21 23:53:17 smsl-n100 volumio[791]: info: Ignoring MPD Status Update May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 7ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 5ms May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 15 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 13 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 9 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: May 21 23:53:17 smsl-n100 volumio[791]: ---------------------------- MPD announces state update: player May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::getState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand status May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 29 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 27 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 24 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 23 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 16 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus stop May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:17 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:17 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 160ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 204ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 201ms May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand status took 170 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 158 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 140 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseState May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:17 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:17 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 324ms May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 307ms May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: sendMpdCommand playlistinfo took 231 milliseconds May 21 23:53:17 smsl-n100 volumio[791]: verbose: ControllerMpd::parseTrackInfo May 21 23:53:17 smsl-n100 volumio[791]: info: ControllerMpd::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::servicePushState May 21 23:53:17 smsl-n100 volumio[791]: verbose: In UPNP mode May 21 23:53:17 smsl-n100 volumio[791]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Golden Key","artist":"Isgaard","album":"Golden Key (#2)","uri":"http://m701.music.126.net/20250522001815/b8a6b621e162599a9a370a7f12fa8e08/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32733881319/3198/eeb2/32d2/d5c67c6c989d42076de7f69109fa5ad0.mp3?vuutv=/IlxorTrG9PbX+1a4Fdfb3ZMovbC8nZzX+NW7xrPHB5Bd6MIIJFOveaYDM04PYx7tcwVUEB3VeywQWqwBt3WpFpMRfuAw4oV3g2ZpasO+l0=","trackType":""} May 21 23:53:17 smsl-n100 volumio[791]: verbose: CURRENT POSITION 0 May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState stateService play May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::syncState currentStatus play May 21 23:53:17 smsl-n100 volumio[791]: info: Received an update from plugin. extracting info from payload May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: info: ------------------------------ 446ms May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::pushState May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioPushState May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output update for this device May 21 23:53:17 smsl-n100 volumio[791]: info: MRS: Pushing multiroomSync output May 21 23:53:17 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:53:17 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: MAXVOL, message: May 21 23:53:17 smsl-n100 volumio[791]: error: Serial API: Failed to decode command: 100, message: undefined May 21 23:53:17 smsl-n100 volumio[791]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 21 23:53:17 smsl-n100 volumio[791]: info: CoreCommandRouter::volumioGetQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CoreStateMachine::getQueue May 21 23:53:17 smsl-n100 volumio[791]: info: CorePlayQueue::getQueue May 21 23:53:34 smsl-n100 volumio[791]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:53:34 smsl-n100 volumio[791]: Error: connect ETIMEDOUT 75.126.115.192:443 May 21 23:53:34 smsl-n100 volumio[791]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 23:53:34 smsl-n100 volumio[791]: errno: -110, May 21 23:53:34 smsl-n100 volumio[791]: code: 'ETIMEDOUT', May 21 23:53:34 smsl-n100 volumio[791]: syscall: 'connect', May 21 23:53:34 smsl-n100 volumio[791]: address: '75.126.115.192', May 21 23:53:34 smsl-n100 volumio[791]: port: 443 May 21 23:53:34 smsl-n100 volumio[791]: } May 21 23:53:34 smsl-n100 volumio[791]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:53:35 smsl-n100 sudo[3753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 23:52 May 21 23:53:35 smsl-n100 sudo[3753]: 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"