-- Logs begin at Thu 2019-02-14 10:12:03 UTC, end at Sat 2025-03-01 15:00:55 UTC. -- Mar 01 14:59:04 volumio volumio[568]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 14:59:05 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 14:59:10 volumio volumio[568]: info: CoreCommandRouter::volumioGetState Mar 01 14:59:10 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:21 volumio volumio[568]: info: CoreCommandRouter::volumioGetQueue Mar 01 14:59:21 volumio volumio[568]: info: CoreStateMachine::getQueue Mar 01 14:59:21 volumio volumio[568]: info: CorePlayQueue::getQueue Mar 01 14:59:24 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 01 14:59:24 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 01 14:59:27 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 01 14:59:30 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 01 14:59:30 volumio volumio[568]: info: Getting webradio selection Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::ClearQueue Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::clearPlayQueue Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::addQueueItems Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::addQueueItems Mar 01 14:59:34 volumio volumio[568]: info: Adding Item to queue: http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3 Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::updateTrackBlock Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::getTrackBlock Mar 01 14:59:34 volumio volumio[568]: info: CoreCommandRouter::volumioPlay Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::play index 0 Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::play index undefined Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:34 volumio volumio[568]: info: CoreStateMachine::startPlaybackTimer Mar 01 14:59:34 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:34 volumio volumio[568]: info: [1740841174669] ControllerWebradio::clearAddPlayTrack Mar 01 14:59:34 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 14:59:34 volumio volumio[568]: info: sendMpdCommand stop took 70 milliseconds Mar 01 14:59:34 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 14:59:34 volumio volumio[568]: info: Mar 01 14:59:34 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:34 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:34 volumio volumio[568]: info: sendMpdCommand clear took 30 milliseconds Mar 01 14:59:34 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Mar 01 14:59:34 volumio volumio[568]: info: Mar 01 14:59:34 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:34 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:34 volumio volumio[568]: info: Mar 01 14:59:34 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:34 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:34 volumio volumio[568]: info: Mar 01 14:59:34 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:34 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:34 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:34 volumio volumio[568]: info: ------------------------------ 130ms Mar 01 14:59:38 volumio volumio[568]: info: CoreCommandRouter::volumioPlay Mar 01 14:59:38 volumio volumio[568]: info: CoreStateMachine::play index undefined Mar 01 14:59:38 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:38 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:38 volumio volumio[568]: info: CoreStateMachine::startPlaybackTimer Mar 01 14:59:38 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:38 volumio volumio[568]: info: [1740841178372] ControllerWebradio::clearAddPlayTrack Mar 01 14:59:38 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 14:59:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 5290ms Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 5273ms Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 5249ms Mar 01 14:59:40 volumio volumio[568]: info: sendMpdCommand stop took 1737 milliseconds Mar 01 14:59:40 volumio volumio[568]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" took 49 milliseconds Mar 01 14:59:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 14:59:40 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 14:59:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand play Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 141ms Mar 01 14:59:40 volumio volumio[568]: info: sendMpdCommand clear took 109 milliseconds Mar 01 14:59:40 volumio volumio[568]: info: sendMpdCommand play took 105 milliseconds Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 98ms Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 86ms Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 74ms Mar 01 14:59:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: info: Mar 01 14:59:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:40 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:40 volumio volumio[568]: info: ------------------------------ 112ms Mar 01 14:59:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" Mar 01 14:59:45 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:45 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:45 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:45 volumio volumio[568]: info: Mar 01 14:59:45 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:45 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 5247ms Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 5237ms Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 5227ms Mar 01 14:59:45 volumio volumio[568]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusiqueclassiqueplus-midfi.mp3" took 43 milliseconds Mar 01 14:59:45 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 14:59:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand play Mar 01 14:59:45 volumio volumio[568]: info: Mar 01 14:59:45 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:45 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:45 volumio volumio[568]: info: Mar 01 14:59:45 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:45 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:45 volumio volumio[568]: info: Mar 01 14:59:45 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:45 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 106ms Mar 01 14:59:45 volumio volumio[568]: info: sendMpdCommand play took 75 milliseconds Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 66ms Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 52ms Mar 01 14:59:45 volumio volumio[568]: info: ------------------------------ 41ms Mar 01 14:59:45 volumio volumio[568]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 14:59:45 volumio volumio[568]: info: CoreStateMachine::ClearQueue Mar 01 14:59:45 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 14:59:45 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:45 volumio volumio[568]: info: CorePlayQueue::clearPlayQueue Mar 01 14:59:45 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 14:59:45 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:45 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 14:59:45 volumio volumio[568]: info: CoreStateMachine::addQueueItems Mar 01 14:59:45 volumio volumio[568]: info: CorePlayQueue::addQueueItems Mar 01 14:59:45 volumio volumio[568]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978 Mar 01 14:59:45 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::updateTrackBlock Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrackBlock Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::volumioPlay Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::play index 0 Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::play index undefined Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::startPlaybackTimer Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: [1740841186136] ControllerWebradio::clearAddPlayTrack Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand stop took 47 milliseconds Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand status took 154 milliseconds Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:46 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand clear took 192 milliseconds Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand status took 160 milliseconds Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand status took 119 milliseconds Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand status took 89 milliseconds Mar 01 14:59:46 volumio volumio[568]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:46 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 14:59:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:46 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 14:59:46 volumio volumio[568]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 01 14:59:46 volumio volumio[568]: info: ------------------------------ 509ms Mar 01 14:59:46 volumio volumio[568]: info: ------------------------------ 480ms Mar 01 14:59:46 volumio volumio[568]: info: ------------------------------ 451ms Mar 01 14:59:46 volumio volumio[568]: info: ------------------------------ 568ms Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:46 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:46 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:46 volumio volumio[568]: info: Mar 01 14:59:46 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:46 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:46 volumio volumio[568]: error: updateQueue error: null Mar 01 14:59:46 volumio volumio[568]: info: ------------------------------ 423ms Mar 01 14:59:47 volumio volumio[568]: info: Mar 01 14:59:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:47 volumio volumio[568]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" took 766 milliseconds Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 440ms Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 429ms Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 419ms Mar 01 14:59:47 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 14:59:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand play Mar 01 14:59:47 volumio volumio[568]: info: Mar 01 14:59:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:47 volumio volumio[568]: info: Mar 01 14:59:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:47 volumio volumio[568]: info: Mar 01 14:59:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 121ms Mar 01 14:59:47 volumio volumio[568]: info: sendMpdCommand play took 73 milliseconds Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 66ms Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 53ms Mar 01 14:59:47 volumio volumio[568]: info: ------------------------------ 41ms Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: sendMpdCommand status took 157 milliseconds Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:51 volumio volumio[568]: info: sendMpdCommand status took 123 milliseconds Mar 01 14:59:51 volumio volumio[568]: info: sendMpdCommand status took 99 milliseconds Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:51 volumio volumio[568]: info: Mar 01 14:59:51 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:51 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:51 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:52 volumio volumio[568]: info: Mar 01 14:59:52 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:52 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:52 volumio volumio[568]: info: Mar 01 14:59:52 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:52 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand status took 282 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand status took 232 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand playlistinfo took 213 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand playlistinfo took 207 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand playlistinfo took 197 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand status took 180 milliseconds Mar 01 14:59:52 volumio volumio[568]: info: sendMpdCommand status took 130 milliseconds Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:52 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:52 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:52 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:52 volumio volumio[568]: 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":"78.129.202.10:8030","artist":"(#1 - 246/5000) Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:52 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus stop Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:52 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:52 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:52 volumio volumio[568]: 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":"78.129.202.10:8030","artist":"(#1 - 246/5000) Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:52 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:52 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:52 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:52 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:52 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:52 volumio volumio[568]: 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":"78.129.202.10:8030","artist":"(#1 - 246/5000) Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:52 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:52 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:52 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:52 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:52 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:53 volumio volumio[568]: info: ------------------------------ 1737ms Mar 01 14:59:53 volumio volumio[568]: info: ------------------------------ 1750ms Mar 01 14:59:53 volumio volumio[568]: info: ------------------------------ 1716ms Mar 01 14:59:53 volumio volumio[568]: info: Mar 01 14:59:53 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:53 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:53 volumio volumio[568]: info: Mar 01 14:59:53 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:53 volumio volumio[568]: info: sendMpdCommand status took 1463 milliseconds Mar 01 14:59:53 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:53 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1351 milliseconds Mar 01 14:59:53 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1353 milliseconds Mar 01 14:59:53 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1314 milliseconds Mar 01 14:59:53 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1309 milliseconds Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:53 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:53 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:53 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:53 volumio volumio[568]: 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":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:53 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:53 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:53 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:53 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:53 volumio volumio[568]: 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":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:53 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:53 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:53 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:53 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:53 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:54 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:54 volumio volumio[568]: 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":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:54 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:54 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:54 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:54 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:54 volumio volumio[568]: 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":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:54 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:54 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:54 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:54 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:54 volumio volumio[568]: info: ------------------------------ 2900ms Mar 01 14:59:54 volumio volumio[568]: info: ------------------------------ 2887ms Mar 01 14:59:54 volumio volumio[568]: info: ------------------------------ 2776ms Mar 01 14:59:54 volumio volumio[568]: info: ------------------------------ 2751ms Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:54 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:54 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:54 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:54 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:54 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:54 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 14:59:54 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 14:59:54 volumio volumio[568]: info: Mar 01 14:59:54 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 14:59:54 volumio volumio[568]: info: ControllerMpd::getState Mar 01 14:59:54 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 14:59:55 volumio volumio[568]: info: ------------------------------ 1710ms Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand status took 1666 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1627 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: ------------------------------ 467ms Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand status took 427 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: ------------------------------ 426ms Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand status took 393 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: ------------------------------ 379ms Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand status took 357 milliseconds Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 14:59:55 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:55 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:55 volumio volumio[568]: 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":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:55 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:55 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: ------------------------------ 3393ms Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand playlistinfo took 216 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand playlistinfo took 208 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand playlistinfo took 206 milliseconds Mar 01 14:59:55 volumio volumio[568]: info: sendMpdCommand playlistinfo took 200 milliseconds Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:55 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 14:59:55 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:55 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:55 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:55 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:55 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:55 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:55 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:55 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:55 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:55 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:55 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:55 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:55 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 14:59:55 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 14:59:55 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"88 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (14:59)","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Mar 01 14:59:55 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 14:59:55 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:55 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:55 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 14:59:56 volumio volumio[568]: info: ------------------------------ 2695ms Mar 01 14:59:56 volumio volumio[568]: info: ------------------------------ 1437ms Mar 01 14:59:56 volumio volumio[568]: info: ------------------------------ 1405ms Mar 01 14:59:56 volumio volumio[568]: info: ------------------------------ 1366ms Mar 01 14:59:59 volumio volumio[568]: info: VolumeController::SetAlsaVolume- Mar 01 14:59:59 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 14:59:59 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 14:59:59 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:00 volumio volumio[568]: info: VolumeController::SetAlsaVolume- Mar 01 15:00:01 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:01 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:01 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:01 volumio volumio[568]: info: VolumeController::SetAlsaVolume- Mar 01 15:00:01 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:01 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:01 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:02 volumio volumio[568]: info: VolumeController::SetAlsaVolume55 Mar 01 15:00:02 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:02 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:02 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:02 volumio volumio[568]: info: VolumeController::SetAlsaVolume53 Mar 01 15:00:02 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:02 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:02 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:03 volumio volumio[568]: info: VolumeController::SetAlsaVolume40 Mar 01 15:00:03 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:03 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:03 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::ClearQueue Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::stPlaybackTimer Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::updateTrackBlock Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrackBlock Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::serviceStop Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::serviceStop Mar 01 15:00:40 volumio volumio[568]: info: [1740841240265] ControllerWebradio::stop Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::clearPlayQueue Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::addQueueItems Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::addQueueItems Mar 01 15:00:40 volumio volumio[568]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591108 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::saveQueue Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::updateTrackBlock Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrackBlock Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPlay Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::play index 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::stop Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::play index undefined Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: [1740841240568] ControllerWebradio::clearAddPlayTrack Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:00:40 volumio volumio[568]: info: Mar 01 15:00:40 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand stop took 346 milliseconds Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand stop took 63 milliseconds Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 15:00:40 volumio volumio[568]: info: Mar 01 15:00:40 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:40 volumio volumio[568]: info: Mar 01 15:00:40 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:40 volumio volumio[568]: info: Mar 01 15:00:40 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:40 volumio volumio[568]: info: Mar 01 15:00:40 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:40 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand status took 86 milliseconds Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand clear took 80 milliseconds Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand status took 68 milliseconds Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand status took 54 milliseconds Mar 01 15:00:40 volumio volumio[568]: info: sendMpdCommand status took 39 milliseconds Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591108" Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:40 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 15:00:40 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:40 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:40 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:40 volumio volumio[568]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 01 15:00:40 volumio volumio[568]: info: ------------------------------ 330ms Mar 01 15:00:40 volumio volumio[568]: info: ------------------------------ 318ms Mar 01 15:00:40 volumio volumio[568]: info: ------------------------------ 306ms Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: error: updateQueue error: null Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 336ms Mar 01 15:00:41 volumio volumio[568]: info: sendMpdCommand playlistinfo took 299 milliseconds Mar 01 15:00:41 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:41 volumio volumio[568]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 443ms Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591108" took 652 milliseconds Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 407ms Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 403ms Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 396ms Mar 01 15:00:41 volumio volumio[568]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 01 15:00:41 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand play Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: Mar 01 15:00:41 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:41 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 137ms Mar 01 15:00:41 volumio volumio[568]: info: sendMpdCommand play took 104 milliseconds Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 96ms Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 85ms Mar 01 15:00:41 volumio volumio[568]: info: ------------------------------ 66ms Mar 01 15:00:45 volumio volumio[568]: info: Mar 01 15:00:45 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:45 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:45 volumio volumio[568]: info: Mar 01 15:00:45 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:45 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:45 volumio volumio[568]: info: Mar 01 15:00:45 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:45 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:45 volumio volumio[568]: info: Mar 01 15:00:45 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:45 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:45 volumio volumio[568]: info: Mar 01 15:00:45 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:45 volumio volumio[568]: info: sendMpdCommand status took 124 milliseconds Mar 01 15:00:45 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:45 volumio volumio[568]: info: sendMpdCommand status took 89 milliseconds Mar 01 15:00:45 volumio volumio[568]: info: sendMpdCommand status took 72 milliseconds Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:45 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: info: Mar 01 15:00:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:46 volumio volumio[568]: info: Mar 01 15:00:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:46 volumio volumio[568]: info: Mar 01 15:00:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:46 volumio volumio[568]: info: Mar 01 15:00:46 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:46 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:46 volumio volumio[568]: info: Mar 01 15:00:46 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand status took 214 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand status took 180 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand playlistinfo took 157 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand playlistinfo took 155 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand playlistinfo took 154 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand status took 135 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: sendMpdCommand status took 123 milliseconds Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:46 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:46 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 34/512) CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:46 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus stop Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:46 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 34/512) CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:46 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:46 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:46 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:46 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:46 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 34/512) CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:46 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:46 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:46 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:46 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:46 volumio volumio[568]: info: ------------------------------ 1164ms Mar 01 15:00:46 volumio volumio[568]: info: ------------------------------ 1137ms Mar 01 15:00:47 volumio volumio[568]: info: ------------------------------ 1119ms Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces system playlist update Mar 01 15:00:47 volumio volumio[568]: info: Ignoring MPD Status Update Mar 01 15:00:47 volumio volumio[568]: info: Mar 01 15:00:47 volumio volumio[568]: ---------------------------- MPD announces state update: player Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::getState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand status Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand status took 1104 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: ------------------------------ 1089ms Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand status took 1011 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1008 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1001 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand playlistinfo took 988 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand playlistinfo took 985 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: ------------------------------ 202ms Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand status took 172 milliseconds Mar 01 15:00:47 volumio volumio[568]: info: ------------------------------ 170ms Mar 01 15:00:47 volumio volumio[568]: info: sendMpdCommand status took 133 milliseconds Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:47 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:47 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:47 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:47 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:47 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:47 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:47 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:47 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:47 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:47 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:47 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:47 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:47 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:47 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:47 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":617,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:47 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:47 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:47 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:47 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: ------------------------------ 2157ms Mar 01 15:00:48 volumio volumio[568]: info: ------------------------------ 2143ms Mar 01 15:00:48 volumio volumio[568]: info: ------------------------------ 2063ms Mar 01 15:00:48 volumio volumio[568]: info: ------------------------------ 2054ms Mar 01 15:00:48 volumio volumio[568]: info: ------------------------------ 1167ms Mar 01 15:00:48 volumio volumio[568]: info: sendMpdCommand status took 1135 milliseconds Mar 01 15:00:48 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1038 milliseconds Mar 01 15:00:48 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1035 milliseconds Mar 01 15:00:48 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1012 milliseconds Mar 01 15:00:48 volumio volumio[568]: info: sendMpdCommand playlistinfo took 1007 milliseconds Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::parseState Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:48 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:48 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:48 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:48 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:48 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:48 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:48 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:48 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1097,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:48 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:48 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:48 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:48 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1965,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:48 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:48 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:48 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:48 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1965,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:48 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:48 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:48 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:48 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:49 volumio volumio[568]: info: ------------------------------ 2949ms Mar 01 15:00:49 volumio volumio[568]: info: ------------------------------ 2891ms Mar 01 15:00:49 volumio volumio[568]: info: ------------------------------ 1964ms Mar 01 15:00:49 volumio volumio[568]: info: ------------------------------ 1929ms Mar 01 15:00:49 volumio volumio[568]: info: sendMpdCommand playlistinfo took 752 milliseconds Mar 01 15:00:49 volumio volumio[568]: verbose: ControllerMpd::parseTrackInfo Mar 01 15:00:49 volumio volumio[568]: info: ControllerMpd::pushState Mar 01 15:00:49 volumio volumio[568]: info: CoreCommandRouter::servicePushState Mar 01 15:00:49 volumio volumio[568]: info: CorePlayQueue::getTrack 0 Mar 01 15:00:49 volumio volumio[568]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1965,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"CKRO FM 97.1","album":null,"uri":"http://ckro.netlantique.com:80/","trackType":"com:80/"} Mar 01 15:00:49 volumio volumio[568]: verbose: CURRENT POSITION 0 Mar 01 15:00:49 volumio volumio[568]: info: CoreStateMachine::syncState stateService play Mar 01 15:00:49 volumio volumio[568]: info: CoreStateMachine::syncState currentStatus play Mar 01 15:00:49 volumio volumio[568]: info: Received an update from plugin. extracting info from payload Mar 01 15:00:49 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 01 15:00:49 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:49 volumio volumio[568]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 15:00:49 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:49 volumio volumio[568]: info: CoreStateMachine::pushState Mar 01 15:00:49 volumio volumio[568]: info: CoreCommandRouter::volumioPushState Mar 01 15:00:49 volumio volumio[568]: info: ------------------------------ 2146ms Mar 01 15:00:51 volumio volumio[568]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:00:52 volumio volumio[568]: Error: connect ECONNREFUSED 162.125.80.5:80 Mar 01 15:00:52 volumio volumio[568]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 01 15:00:52 volumio volumio[568]: errno: -111, Mar 01 15:00:52 volumio volumio[568]: code: 'ECONNREFUSED', Mar 01 15:00:52 volumio volumio[568]: syscall: 'connect', Mar 01 15:00:52 volumio volumio[568]: address: '162.125.80.5', Mar 01 15:00:52 volumio volumio[568]: port: 80 Mar 01 15:00:52 volumio volumio[568]: } Mar 01 15:00:52 volumio volumio[568]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:00:55 volumio sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 14:59 Mar 01 15:00:55 volumio sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"