-- Logs begin at Thu 2024-10-17 09:14:08 CST, end at Tue 2024-10-22 20:43:44 CST. -- Oct 22 20:42:01 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:42:07 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: NETWORKSTATUS, message: undefined Oct 22 20:42:07 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: NETWORKSTATUS, message: undefined Oct 22 20:42:07 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: NETWORKSTATUS Oct 22 20:42:07 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: NETWORKSTATUS Oct 22 20:42:08 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: NETWORKSTATUS Oct 22 20:42:09 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: NETWORKSTATUS, message: undefined Oct 22 20:42:09 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: NETWORKSTATUS, message: undefined Oct 22 20:42:09 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: NETWORKSTATUS, message: undefined Oct 22 20:42:15 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: NETWORKSTATUS Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Oct 22 20:42:20 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Oct 22 20:42:20 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Oct 22 20:42:20 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand status took 6 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand status took 3 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210654/d3b6bdf0f98e21c09660a40ef0f9c2bd/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::stPlaybackTimer Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210654/d3b6bdf0f98e21c09660a40ef0f9c2bd/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210654/d3b6bdf0f98e21c09660a40ef0f9c2bd/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:42:20 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:20 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ------------------------------ 134ms Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ------------------------------ 133ms Oct 22 20:42:20 smsl-d400n volumio[1154]: info: ------------------------------ 133ms Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:20 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:20 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:20 smsl-d400n volumio[1154]: info: Clearing queue after UPNP request Oct 22 20:42:20 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreStateMachine::ClearQueue Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreStateMachine::stop Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CorePlayQueue::clearPlayQueue Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CorePlayQueue::saveQueue Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:21 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:21 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:21 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:21 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Starting UPNP Playback Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Preparing playback through UPNP Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:21 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 20:42:21 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 2ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Oct 22 20:42:21 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:21 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 4 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Oct 22 20:42:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 13 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 6 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 76ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 95ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 93ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 72 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"24 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"永远记得你","artist":"刘德华","album":"回到你身边","uri":"http://m8.music.126.net/20241022210720/fc06dd17754befc5a0a2e7bda5ff0df8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/3038205167/e5df/0182/410c/657022cd598b140a00fd337c8f3746fe.m4a","trackType":"m4a"} Oct 22 20:42:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 179ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 171ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: ------------------------------ 169ms Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:22 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Oct 22 20:42:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Oct 22 20:42:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:42:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 2 milliseconds Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::stPlaybackTimer Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ------------------------------ 83ms Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ------------------------------ 80ms Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Oct 22 20:42:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 1 milliseconds Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 20:42:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:25 smsl-d400n volumio[1154]: info: ------------------------------ 34ms Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:42:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:25 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:25 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:26 smsl-d400n volumiologrotate[700]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 22 20:42:26 smsl-d400n volumiologrotate[700]: ls: cannot access 'D400N': No such file or directory Oct 22 20:42:28 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:28 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:42:28 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:31 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:31 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:32 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:42:34 smsl-d400n dhcpcd[758]: eth0: soliciting a DHCP lease Oct 22 20:42:35 smsl-d400n dhcpcd[758]: eth0: offered 192.168.2.10 from 192.168.2.1 Oct 22 20:42:35 smsl-d400n dhcpcd[758]: eth0: probing address 192.168.2.10/24 Oct 22 20:42:35 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:42:41 smsl-d400n dhcpcd[758]: eth0: leased 192.168.2.10 for 86400 seconds Oct 22 20:42:41 smsl-d400n dhcpcd[758]: eth0: adding route to 192.168.2.0/24 Oct 22 20:42:41 smsl-d400n avahi-daemon[705]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.10. Oct 22 20:42:41 smsl-d400n dhcpcd[758]: eth0: adding default route via 192.168.2.1 Oct 22 20:42:41 smsl-d400n avahi-daemon[705]: New relevant interface eth0.IPv4 for mDNS. Oct 22 20:42:41 smsl-d400n avahi-daemon[705]: Registering new address record for 192.168.2.10 on eth0.IPv4. Oct 22 20:42:41 smsl-d400n dhcpcd[758]: eth0: dhcp_envoption 213: Operation not supported Oct 22 20:42:41 smsl-d400n dhcpcd[758]: eth0: dhcp_envoption 213: Operation not supported Oct 22 20:42:41 smsl-d400n ntpd[1143]: ntpd exiting on signal 15 (Terminated) Oct 22 20:42:41 smsl-d400n ntpd[1143]: 162.159.200.1 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n systemd[1]: Stopping Network Time Service... Oct 22 20:42:41 smsl-d400n ntpd[1143]: 202.118.1.81 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n ntpd[1143]: 219.216.128.25 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n systemd[1]: ntp.service: Succeeded. Oct 22 20:42:41 smsl-d400n ntpd[1143]: 5.79.108.34 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n ntpd[1143]: 84.16.67.12 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n ntpd[1143]: 119.28.206.193 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n ntpd[1143]: 117.80.112.205 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n ntpd[1143]: 202.112.29.82 local addr 192.168.2.9 -> Oct 22 20:42:41 smsl-d400n systemd[1]: Stopped Network Time Service. Oct 22 20:42:41 smsl-d400n volumio[1154]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 22 20:42:41 smsl-d400n volumio[1154]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Clearing queue after UPNP request Oct 22 20:42:41 smsl-d400n systemd[1]: Starting Network Time Service... Oct 22 20:42:41 smsl-d400n volumio[1154]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 22 20:42:41 smsl-d400n ntpd[3064]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 22 20:42:41 smsl-d400n ntpd[3064]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 22 20:42:41 smsl-d400n systemd[1]: Started Network Time Service. Oct 22 20:42:41 smsl-d400n ntpd[3070]: proto: precision = 1.166 usec (-20) Oct 22 20:42:41 smsl-d400n ntpd[3070]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 22 20:42:41 smsl-d400n ntpd[3070]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 22 20:42:41 smsl-d400n ntpd[3070]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 665 days ago Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listen and drop on 0 v6wildcard [::]:123 Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listen normally on 2 lo 127.0.0.1:123 Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listen normally on 3 eth0 192.168.2.10:123 Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listen normally on 4 wlan0 192.168.2.9:123 Oct 22 20:42:41 smsl-d400n ntpd[3070]: Listening on routing socket on fd #21 for interface updates Oct 22 20:42:41 smsl-d400n ntpd[3070]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 22 20:42:41 smsl-d400n ntpd[3070]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreStateMachine::ClearQueue Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreStateMachine::stop Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CorePlayQueue::clearPlayQueue Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CorePlayQueue::saveQueue Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:41 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:41 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Oct 22 20:42:41 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Oct 22 20:42:41 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Oct 22 20:42:41 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:41 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:41 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:41 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:41 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:41 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:41 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Starting UPNP Playback Oct 22 20:42:41 smsl-d400n volumio[1154]: info: Preparing playback through UPNP Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:41 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 20:42:41 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Discovery: this is already registered, 67fd5678-627e-4dc1-b8d3-0d718a3ab66c Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Discovery: Found device SMSL D400N Oct 22 20:42:42 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 10ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 9ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 10ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 2ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Oct 22 20:42:42 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:42 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:42 smsl-d400n ntpd[3070]: Soliciting pool server 162.159.200.1 Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Oct 22 20:42:43 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Oct 22 20:42:43 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Oct 22 20:42:43 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand status took 11 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand status took 11 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand status took 19 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 12 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 10 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: 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":"戏子无情 (Live)","artist":"黄凯芹","album":"细听 黄凯芹35周年演唱会2022 (Live)","uri":"http://m801.music.126.net/20241022210741/ad2f15536472994e071008c2e16cdc78/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/30090111219/0f6f/b3ec/d667/2cd2dc7e8fb735bf7a688ddfa35a5115.m4a","trackType":"m4a"} Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: 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":"戏子无情 (Live)","artist":"黄凯芹","album":"细听 黄凯芹35周年演唱会2022 (Live)","uri":"http://m801.music.126.net/20241022210741/ad2f15536472994e071008c2e16cdc78/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/30090111219/0f6f/b3ec/d667/2cd2dc7e8fb735bf7a688ddfa35a5115.m4a","trackType":"m4a"} Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ------------------------------ 100ms Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ------------------------------ 99ms Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 106 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: 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":"戏子无情 (Live)","artist":"黄凯芹","album":"细听 黄凯芹35周年演唱会2022 (Live)","uri":"http://m801.music.126.net/20241022210741/ad2f15536472994e071008c2e16cdc78/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/30090111219/0f6f/b3ec/d667/2cd2dc7e8fb735bf7a688ddfa35a5115.m4a","trackType":"m4a"} Oct 22 20:42:43 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:43 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:43 smsl-d400n volumio[1154]: info: ------------------------------ 157ms Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:43 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:43 smsl-d400n ntpd[3070]: Soliciting pool server 119.28.206.193 Oct 22 20:42:43 smsl-d400n ntpd[3070]: Soliciting pool server 202.112.29.82 Oct 22 20:42:44 smsl-d400n ntpd[3070]: Soliciting pool server 202.118.1.130 Oct 22 20:42:44 smsl-d400n ntpd[3070]: Soliciting pool server 117.80.112.205 Oct 22 20:42:44 smsl-d400n ntpd[3070]: Soliciting pool server 108.59.2.24 Oct 22 20:42:45 smsl-d400n ntpd[3070]: Soliciting pool server 202.112.31.197 Oct 22 20:42:45 smsl-d400n ntpd[3070]: Soliciting pool server 5.79.108.34 Oct 22 20:42:45 smsl-d400n ntpd[3070]: Soliciting pool server 219.216.128.25 Oct 22 20:42:46 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:46 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:46 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:46 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:46 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:42:46 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:42:46 smsl-d400n ntpd[3070]: Soliciting pool server 84.16.67.12 Oct 22 20:42:46 smsl-d400n ntpd[3070]: Soliciting pool server 162.159.200.123 Oct 22 20:42:47 smsl-d400n ntpd[3070]: Soliciting pool server 5.79.108.34 Oct 22 20:42:54 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Clearing queue after UPNP request Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreStateMachine::ClearQueue Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreStateMachine::stop Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CorePlayQueue::clearPlayQueue Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CorePlayQueue::saveQueue Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:55 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:55 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Oct 22 20:42:55 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Oct 22 20:42:55 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Oct 22 20:42:55 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:55 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:55 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:55 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:55 smsl-d400n volumio[1154]: info: ------------------------------ 8ms Oct 22 20:42:55 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:55 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Starting UPNP Playback Oct 22 20:42:55 smsl-d400n volumio[1154]: info: Preparing playback through UPNP Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:55 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 20:42:55 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 7ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 8ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 10ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 8ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Oct 22 20:42:56 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand status took 9 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand status took 8 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand status took 16 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 12 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 11 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:56 smsl-d400n volumio[1154]: 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":"Hold Me","artist":"Hamidshax","album":"Hold Me","uri":"http://stream4.qqmusic.qq.com/003mmned3QgXqQ.wma","trackType":"wma"} Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:56 smsl-d400n volumio[1154]: 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":"Hold Me","artist":"Hamidshax","album":"Hold Me","uri":"http://stream4.qqmusic.qq.com/003mmned3QgXqQ.wma","trackType":"wma"} Oct 22 20:42:56 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:56 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 95ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: ------------------------------ 95ms Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:56 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: sendMpdCommand clearerror took 104 milliseconds Oct 22 20:42:57 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 102 milliseconds Oct 22 20:42:57 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:57 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:57 smsl-d400n volumio[1154]: 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":"Hold Me","artist":"Hamidshax","album":"Hold Me","uri":"http://stream4.qqmusic.qq.com/003mmned3QgXqQ.wma","trackType":"wma"} Oct 22 20:42:57 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:57 smsl-d400n volumio[1154]: info: No code Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:57 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:57 smsl-d400n volumio[1154]: info: ------------------------------ 150ms Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:57 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Clearing queue after UPNP request Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreStateMachine::ClearQueue Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreStateMachine::stop Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CorePlayQueue::clearPlayQueue Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CorePlayQueue::saveQueue Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:42:58 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:42:58 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:58 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:58 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Starting UPNP Playback Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Preparing playback through UPNP Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:42:58 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 20:42:58 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Oct 22 20:42:58 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:42:58 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 11 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 8 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: Oct 22 20:42:59 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:42:59 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 23 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 8 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:42:59 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 87ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 95ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 92ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 22 20:43:00 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 22 20:43:00 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":255,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:00 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:00 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 158ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 146ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: ------------------------------ 138ms Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:00 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:43:03 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:03 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:03 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:03 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:03 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:03 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:10 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand status took 4 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand status took 3 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::stPlaybackTimer Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: No code Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: 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":"Fireworks (Live)","artist":"First Aid Kit","album":"Live from the Rebel Hearts Club","uri":"http://m701.music.126.net/20241022210758/12df4c65463123e2a241660635e92702/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32090937224/9e86/06fc/5d21/187147d4a950027558313624a17701da.m4a","trackType":"m4a"} Oct 22 20:43:24 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: No code Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 112ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 111ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 109ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Clearing queue after UPNP request Oct 22 20:43:24 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::ClearQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::stop Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::clearPlayQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::saveQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:24 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Oct 22 20:43:24 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:24 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:43:24 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:43:24 smsl-d400n volumio[1154]: error: updateQueue error: null Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Starting UPNP Playback Oct 22 20:43:24 smsl-d400n volumio[1154]: info: Preparing playback through UPNP Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetState Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 0 Oct 22 20:43:24 smsl-d400n volumio[1154]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 20:43:24 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 6ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 2ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 2ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 9ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 8ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 5ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 4ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 3ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 9 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Oct 22 20:43:25 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 21 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 18 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 8 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 58ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 73ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:25 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:25 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 137ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 135ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 129ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: ------------------------------ 121ms Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:25 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Oct 22 20:43:25 smsl-d400n volumiologrotate[700]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 22 20:43:26 smsl-d400n volumiologrotate[700]: ls: cannot access 'D400N': No such file or directory Oct 22 20:43:28 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:28 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:28 smsl-d400n volumio[1154]: info: Executing endpoint metavolumio Oct 22 20:43:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Oct 22 20:43:29 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Oct 22 20:43:29 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Oct 22 20:43:29 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::getState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand status took 22 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand status took 19 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand status took 16 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96000,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96000,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: In UPNP mode Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96000,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Could This Be Love (Mick G Mix)","artist":"Jennifer Lopez","album":"On The 6","uri":"http://m701.music.126.net/20241022210824/bf58c1b4dc84a08c3d63916c97b02349/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32049204062/a338/9fac/fbc3/fea06bf88fbf38780c02f1585dfb2c4f.m4a","trackType":"m4a"} Oct 22 20:43:29 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 0 Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Oct 22 20:43:29 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ------------------------------ 112ms Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ------------------------------ 109ms Oct 22 20:43:29 smsl-d400n volumio[1154]: info: ------------------------------ 107ms Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: Updating RAAT Signal Path Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CoreStateMachine::getQueue Oct 22 20:43:29 smsl-d400n volumio[1154]: info: CorePlayQueue::getQueue Oct 22 20:43:31 smsl-d400n volumio[1154]: error: Failed request for metavolumio API Oct 22 20:43:43 smsl-d400n volumio[1154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:43:43 smsl-d400n volumio[1154]: Error: connect ETIMEDOUT 185.60.219.36:443 Oct 22 20:43:43 smsl-d400n volumio[1154]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 22 20:43:43 smsl-d400n volumio[1154]: errno: -110, Oct 22 20:43:43 smsl-d400n volumio[1154]: code: 'ETIMEDOUT', Oct 22 20:43:43 smsl-d400n volumio[1154]: syscall: 'connect', Oct 22 20:43:43 smsl-d400n volumio[1154]: address: '185.60.219.36', Oct 22 20:43:43 smsl-d400n volumio[1154]: port: 443 Oct 22 20:43:43 smsl-d400n volumio[1154]: } Oct 22 20:43:43 smsl-d400n volumio[1154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:43:44 smsl-d400n sudo[3729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 20:42 Oct 22 20:43:44 smsl-d400n sudo[3729]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 26 Sep 2024 02:01:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="14faf490d09f3983d26ad2f504844908"