-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-05-22 01:57:45 CST. -- May 22 01:56:12 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:56:12 smsl-n100 volumio[1046]: info: [1747850172244] ControllerWebradio::stop May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:56:12 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:12 smsl-n100 volumio[1046]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598122 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: [1747850172289] ControllerWebradio::clearAddPlayTrack May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:12 smsl-n100 volumio[1046]: info: Updating RAAT Signal Path May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 68 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 25 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:12 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:12 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand status took 23 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 16 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand status took 14 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598122" May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:12 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:12 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 69ms May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 68ms May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 79ms May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 74 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:12 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 107ms May 22 01:56:12 smsl-n100 volumio[1046]: info: Updating RAAT Signal Path May 22 01:56:12 smsl-n100 volumio[1046]: info: Updating RAAT Signal Path May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99598122" took 249 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:56:12 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 201ms May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 199ms May 22 01:56:12 smsl-n100 volumio[1046]: info: sendMpdCommand play took 6 milliseconds May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: info: May 22 01:56:12 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:12 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 16ms May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 10ms May 22 01:56:12 smsl-n100 volumio[1046]: info: ------------------------------ 6ms May 22 01:56:12 smsl-n100 volumio[1046]: info: VolumeController::SetAlsaVolume0 May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: info: Updating RAAT Signal Path May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:12 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:12 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:12 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:12 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 29 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 27 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 22 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 17 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:13 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:13 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:13 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:13 smsl-n100 volumio[1046]: info: May 22 01:56:13 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 43 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand status took 36 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 32 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 30 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 30 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 29 milliseconds May 22 01:56:13 smsl-n100 volumio[1046]: info: ------------------------------ 23ms May 22 01:56:13 smsl-n100 volumio[1046]: info: ------------------------------ 20ms May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:13 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:13 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:13 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:13 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:13 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:13 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:13 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:13 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:13 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:13 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:13 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:13 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:13 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:13 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 179ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 236ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 233ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 229ms May 22 01:56:14 smsl-n100 volumio[1046]: info: May 22 01:56:14 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:14 smsl-n100 volumio[1046]: info: May 22 01:56:14 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 199ms May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand status took 191 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 184 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 182 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand status took 7 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"135 Kbps","isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"135 Kbps","isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 343ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 344ms May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 183 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 184 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 183 milliseconds May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:14 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1109,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"135 Kbps","isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"135 Kbps","isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:14 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"135 Kbps","isStreaming":false,"title":"Manuel De Falla (1876-1946) - 'El sombrero de tres picos' - Balletto in 1 atto (39:14) (Teresa Berganza, mezzosoprano - Boston Symphony Orchestra - Seiji Ozawa, direttore) {+info: veniceclassicradio.eu}","artist":"VCR Live Hi-Fi | Venice Classic Radio Italia","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 22 01:56:14 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:14 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:14 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:14 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 534ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 354ms May 22 01:56:14 smsl-n100 volumio[1046]: info: ------------------------------ 347ms May 22 01:56:24 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:56:24 smsl-n100 volumio[1046]: info: [1747850184216] ControllerWebradio::stop May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:56:24 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:24 smsl-n100 volumio[1046]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99544935 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: [1747850184259] ControllerWebradio::clearAddPlayTrack May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 65 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 23 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 23 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 17 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 13 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99544935" May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 69ms May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 66ms May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 79ms May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 74 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:24 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 107ms May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:24 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:24 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:24 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:24 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99544935" took 248 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:56:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 201ms May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 198ms May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: info: May 22 01:56:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:24 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:24 smsl-n100 volumio[1046]: info: sendMpdCommand play took 17 milliseconds May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 16ms May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 11ms May 22 01:56:24 smsl-n100 volumio[1046]: info: ------------------------------ 8ms May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 19 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 14 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 46 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 43 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 41 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 40 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 22 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 17 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 184/251) Dance UK Radio danceradiouk aac+","album":null,"uri":"http://80.85.84.114:8024/stream","trackType":""} May 22 01:56:25 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 184/251) Dance UK Radio danceradiouk aac+","album":null,"uri":"http://80.85.84.114:8024/stream","trackType":""} May 22 01:56:25 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:25 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Junior Jack - Organic","artist":"Dance UK Radio danceradiouk aac+","album":null,"uri":"http://80.85.84.114:8024/stream","trackType":""} May 22 01:56:25 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:25 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 158ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 231ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 227ms May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:56:25 smsl-n100 volumio[1046]: info: [1747850185388] ControllerWebradio::stop May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:56:25 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:25 smsl-n100 volumio[1046]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1882605 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: [1747850185447] ControllerWebradio::clearAddPlayTrack May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 295ms May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 284 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 280 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 273 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 272 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 124ms May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 117 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 115ms May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 110 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 74 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 19 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 372ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 353ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 333ms May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 121 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 116 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 113 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 113 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 111 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 98 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand status took 96 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1882605" May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 466ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 290ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 282ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 161ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 158ms May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 115ms May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 107 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:25 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 248ms May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:25 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:25 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:25 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:25 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1882605" took 268 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 212ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 210ms May 22 01:56:25 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:56:25 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: May 22 01:56:25 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:25 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 35ms May 22 01:56:25 smsl-n100 volumio[1046]: info: sendMpdCommand play took 31 milliseconds May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 12ms May 22 01:56:25 smsl-n100 volumio[1046]: info: ------------------------------ 9ms May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 15 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 13 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 9 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 21 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 8 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:27 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:27 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 107ms May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 136ms May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 133ms May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 99 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 97 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 97 milliseconds May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:27 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:27 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vocaltrance","artist":"(#1 - 84/1000) Radioparty.pl - Vocal Trance","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:27 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:27 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 315ms May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 304ms May 22 01:56:27 smsl-n100 volumio[1046]: info: ------------------------------ 302ms May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:27 smsl-n100 volumio[1046]: info: May 22 01:56:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:28 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:28 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:28 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 36ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 33 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 23ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 20 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 19ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 16 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:28 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:28 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:28 smsl-n100 volumio[1046]: info: May 22 01:56:28 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 42ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 38 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 33 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 32 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 32 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 30ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 28 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 26ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand status took 23 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 203ms May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 191ms May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 188ms May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 180 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 176 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 175 milliseconds May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:28 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"facebook.com/Radioparty.Official","artist":"RadioParty.pl KANAL VOCAL TRANCE","album":null,"uri":"http://s2.radioparty.pl:8000/vocaltrance","trackType":""} May 22 01:56:28 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:28 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 351ms May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 338ms May 22 01:56:28 smsl-n100 volumio[1046]: info: ------------------------------ 335ms May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:28 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:28 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:28 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:28 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:28 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:28 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:30 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:56:30 smsl-n100 volumio[1046]: info: [1747850190308] ControllerWebradio::stop May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:56:30 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:30 smsl-n100 volumio[1046]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99525189 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: [1747850190345] ControllerWebradio::clearAddPlayTrack May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 56 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 21 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:30 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:30 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand status took 17 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 15 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand status took 13 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99525189" May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:30 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:30 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 64ms May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 61ms May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 73ms May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 67 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:30 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 100ms May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:30 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:30 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:30 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:30 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:30 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:30 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99525189" took 248 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:56:30 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 206ms May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 204ms May 22 01:56:30 smsl-n100 volumio[1046]: info: sendMpdCommand play took 6 milliseconds May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: info: May 22 01:56:30 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:30 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 12ms May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 7ms May 22 01:56:30 smsl-n100 volumio[1046]: info: ------------------------------ 5ms May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 7 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 15 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 14 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 7 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 40ms May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 19 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 10 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 6 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 152ms May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 145ms May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 143ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 108 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 108 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 88/1000) Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 217ms May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 216ms May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 39ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 36 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 22ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 19 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 18ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 16 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:56:31 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:56:31 smsl-n100 volumio[1046]: info: May 22 01:56:31 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 66ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 62 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 55 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 55 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 55 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 26ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 23 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 21ms May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand status took 19 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 238ms May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 221ms May 22 01:56:31 smsl-n100 volumio[1046]: info: ------------------------------ 219ms May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:31 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:56:31 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:56:31 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 203 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 199 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 198 milliseconds May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:56:31 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jonathan Fritzén - Undercover","artist":"Radio Radio Network - Marbella v2","album":null,"uri":"http://46.105.100.126:8000/stream","trackType":""} May 22 01:56:31 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:56:31 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:56:31 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:56:31 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:56:32 smsl-n100 volumio[1046]: info: ------------------------------ 388ms May 22 01:56:32 smsl-n100 volumio[1046]: info: ------------------------------ 347ms May 22 01:56:32 smsl-n100 volumio[1046]: info: ------------------------------ 343ms May 22 01:56:39 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:56:40 smsl-n100 volumio[1046]: info: READ May 22 01:56:40 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:56:51 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:56:51 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/local May 22 01:56:59 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:56:59 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/best May 22 01:57:03 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:03 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/best May 22 01:57:04 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:04 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/best May 22 01:57:07 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:07 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/local May 22 01:57:08 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:08 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/local May 22 01:57:10 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:10 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/local May 22 01:57:13 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:13 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/popular May 22 01:57:15 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:15 smsl-n100 volumio[1046]: info: Getting BBC Radios May 22 01:57:16 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:17 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:57:17 smsl-n100 volumio[1046]: info: [1747850237050] ControllerWebradio::stop May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:57:17 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:17 smsl-n100 volumio[1046]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: [1747850237095] ControllerWebradio::clearAddPlayTrack May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 71 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 27 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:17 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:17 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand status took 18 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 17 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand status took 13 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8" May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:17 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:57:17 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:17 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:17 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 67ms May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 65ms May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 85ms May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 79 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8" took 80 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 29ms May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 26ms May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:57:17 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:57:17 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 113ms May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: info: May 22 01:57:17 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:17 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 49ms May 22 01:57:17 smsl-n100 volumio[1046]: info: sendMpdCommand play took 41 milliseconds May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 38ms May 22 01:57:17 smsl-n100 volumio[1046]: info: ------------------------------ 35ms May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:17 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:17 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:57:17 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:17 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:57:20 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:57:20 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:20 smsl-n100 volumio[1046]: info: Adding Item to queue: http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: [1747850240087] ControllerWebradio::clearAddPlayTrack May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 392 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:20 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 8 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8" May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:20 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:20 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand status took 19 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 23ms May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand add "http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8" took 18 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand status took 14 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:20 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:20 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 01:57:20 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: No code May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:20 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 01:57:20 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: No code May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:20 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 01:57:20 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: No code May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:20 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:20 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 185ms May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 177ms May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 176ms May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:20 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:20 smsl-n100 volumio[1046]: info: May 22 01:57:20 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:20 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 184ms May 22 01:57:20 smsl-n100 volumio[1046]: info: sendMpdCommand play took 174 milliseconds May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 12ms May 22 01:57:20 smsl-n100 volumio[1046]: info: ------------------------------ 8ms May 22 01:57:22 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:57:22 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:22 smsl-n100 volumio[1046]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8 May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:22 smsl-n100 volumio[1046]: info: [1747850242425] ControllerWebradio::clearAddPlayTrack May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 10 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:22 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand status took 5 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 5 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8" May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:22 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:22 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:22 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:22 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 18ms May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 14 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8" took 15 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand status took 8 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:22 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:22 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:57:22 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:22 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 01:57:22 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: No code May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:22 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 01:57:22 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: No code May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:22 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:22 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 96ms May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 130ms May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 130ms May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:22 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:22 smsl-n100 volumio[1046]: info: May 22 01:57:22 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:22 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 134ms May 22 01:57:22 smsl-n100 volumio[1046]: info: sendMpdCommand play took 127 milliseconds May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 9ms May 22 01:57:22 smsl-n100 volumio[1046]: info: ------------------------------ 7ms May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 23 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 20 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 12 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: May 22 01:57:24 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 37 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 30 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 27 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 27 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 20 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand status took 16 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:57:24 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:57:24 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: ------------------------------ 156ms May 22 01:57:24 smsl-n100 volumio[1046]: info: ------------------------------ 193ms May 22 01:57:24 smsl-n100 volumio[1046]: info: ------------------------------ 185ms May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 130 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 125 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 125 milliseconds May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:57:24 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:57:24 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one_dance-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_62063831/live/ww/bbc_radio_one_dance/bbc_radio_one_dance.isml/bbc_radio_one_dance-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 22 01:57:24 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService play May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus play May 22 01:57:24 smsl-n100 volumio[1046]: info: Received an update from plugin. extracting info from payload May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:24 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:24 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:25 smsl-n100 volumio[1046]: info: ------------------------------ 308ms May 22 01:57:25 smsl-n100 volumio[1046]: info: ------------------------------ 286ms May 22 01:57:25 smsl-n100 volumio[1046]: info: ------------------------------ 283ms May 22 01:57:27 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::ClearQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::stPlaybackTimer May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::serviceStop May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::serviceStop May 22 01:57:27 smsl-n100 volumio[1046]: info: [1747850247347] ControllerWebradio::stop May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::clearPlayQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::addQueueItems May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::addQueueItems May 22 01:57:27 smsl-n100 volumio[1046]: info: Preload queue cleared May 22 01:57:27 smsl-n100 volumio[1046]: info: Adding Item to queue: http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::saveQueue May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::updateTrackBlock May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrackBlock May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPlay May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::play index 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::stop May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::play index undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::startPlaybackTimer May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: [1747850247388] ControllerWebradio::clearAddPlayTrack May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand stop May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 59 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand stop took 20 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clear May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:27 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 17 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand clear took 15 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 11 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand status took 9 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand add "http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8" May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:57:27 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:27 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:27 smsl-n100 volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 70ms May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 67ms May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: error: updateQueue error: null May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 80ms May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 75 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand add "http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8" took 76 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 21ms May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 18ms May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 01:57:27 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand play May 22 01:57:27 smsl-n100 volumio[1046]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 107ms May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: info: May 22 01:57:27 smsl-n100 volumio[1046]: ---------------------------- MPD announces system playlist update May 22 01:57:27 smsl-n100 volumio[1046]: info: Ignoring MPD Status Update May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 42ms May 22 01:57:27 smsl-n100 volumio[1046]: info: sendMpdCommand play took 35 milliseconds May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 32ms May 22 01:57:27 smsl-n100 volumio[1046]: info: ------------------------------ 30ms May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:27 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:27 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: MAXVOL, message: May 22 01:57:27 smsl-n100 volumio[1046]: error: Serial API: Failed to decode command: 100, message: undefined May 22 01:57:27 smsl-n100 volumio[1046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 22 01:57:33 smsl-n100 volumio[1046]: info: May 22 01:57:33 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:33 smsl-n100 volumio[1046]: info: May 22 01:57:33 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:33 smsl-n100 volumio[1046]: info: May 22 01:57:33 smsl-n100 volumio[1046]: ---------------------------- MPD announces state update: player May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::getState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand status May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clearerror May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand status took 20 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clearerror May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand status took 18 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand clearerror May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand status took 17 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseState May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand clearerror took 23 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand clearerror took 22 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand clearerror took 19 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 10 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 8 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: info: sendMpdCommand playlistinfo took 7 milliseconds May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:33 smsl-n100 volumio[1046]: verbose: ControllerMpd::parseTrackInfo May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:33 smsl-n100 volumio[1046]: 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":"bbc_radio_one.m3u8","artist":null,"album":null,"uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","trackType":"m3u8"} May 22 01:57:33 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: No code May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:33 smsl-n100 volumio[1046]: 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":"bbc_radio_one.m3u8","artist":null,"album":null,"uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","trackType":"m3u8"} May 22 01:57:33 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: No code May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: ControllerMpd::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::servicePushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: CorePlayQueue::getTrack 0 May 22 01:57:33 smsl-n100 volumio[1046]: 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":"bbc_radio_one.m3u8","artist":null,"album":null,"uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","trackType":"m3u8"} May 22 01:57:33 smsl-n100 volumio[1046]: verbose: CURRENT POSITION 0 May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState stateService stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::syncState currentStatus stop May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: No code May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreStateMachine::pushState May 22 01:57:33 smsl-n100 volumio[1046]: info: CoreCommandRouter::volumioPushState May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output update for this device May 22 01:57:33 smsl-n100 volumio[1046]: info: MRS: Pushing multiroomSync output May 22 01:57:33 smsl-n100 volumio[1046]: info: ------------------------------ 180ms May 22 01:57:33 smsl-n100 volumio[1046]: info: ------------------------------ 178ms May 22 01:57:33 smsl-n100 volumio[1046]: info: ------------------------------ 177ms May 22 01:57:36 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:36 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/local May 22 01:57:37 smsl-n100 volumio[1046]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 01:57:37 smsl-n100 volumio[1046]: info: TuneIn handleBrowseUri: tunein/location May 22 01:57:44 smsl-n100 volumio[1046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 01:57:44 smsl-n100 volumio[1046]: Error: connect ETIMEDOUT 31.13.67.41:80 May 22 01:57:44 smsl-n100 volumio[1046]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 22 01:57:44 smsl-n100 volumio[1046]: errno: -110, May 22 01:57:44 smsl-n100 volumio[1046]: code: 'ETIMEDOUT', May 22 01:57:44 smsl-n100 volumio[1046]: syscall: 'connect', May 22 01:57:44 smsl-n100 volumio[1046]: address: '31.13.67.41', May 22 01:57:44 smsl-n100 volumio[1046]: port: 80 May 22 01:57:44 smsl-n100 volumio[1046]: } May 22 01:57:44 smsl-n100 volumio[1046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 01:57:45 smsl-n100 sudo[3568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 01:56 May 22 01:57:45 smsl-n100 sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"