-- Logs begin at Fri 2025-06-13 13:54:06 CST, end at Fri 2025-06-13 14:19:45 CST. -- Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 13 14:18:03 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 13 14:18:05 volumio volumio[5680]: info: BOOT COMPLETED Jun 13 14:18:17 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 14:18:19 volumio volumio[5680]: info: CoreCommandRouter::Close All Modals sent Jun 13 14:18:40 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:40 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:18:46 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:46 volumio volumio[5680]: info: TuneIn handleBrowseUri: tunein/local Jun 13 14:18:50 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:50 volumio volumio[5680]: info: TuneIn handleBrowseUri: tunein/local Jun 13 14:18:51 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:51 volumio volumio[5680]: info: TuneIn handleBrowseUri: tunein/local Jun 13 14:18:52 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:54 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:54 volumio volumio[5680]: info: TuneIn handleBrowseUri: tunein/best Jun 13 14:18:56 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:56 volumio volumio[5680]: info: TuneIn handleBrowseUri: tunein/best Jun 13 14:18:57 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 13 14:18:57 volumio volumio[5680]: info: Getting BBC Radios Jun 13 14:18:58 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:18:59 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:19:05 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::ClearQueue Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::stop Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::stPlaybackTimer Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::updateTrackBlock Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrackBlock Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::serviceStop Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::serviceStop Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::stop Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::clearPlayQueue Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::saveQueue Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPushQueue Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::addQueueItems Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::addQueueItems Jun 13 14:19:05 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:19:05 volumio volumio[5680]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPushQueue Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::saveQueue Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::updateTrackBlock Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrackBlock Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::play index 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::stop Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::startPlaybackTimer Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: [1749795545716] ControllerWebradio::clearAddPlayTrack Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand stop took 27 milliseconds Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand stop took 9 milliseconds Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:05 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand status took 10 milliseconds Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand clear took 10 milliseconds Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand status took 9 milliseconds Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:05 volumio volumio[5680]: info: ------------------------------ 24ms Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:05 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:05 volumio volumio[5680]: info: Jun 13 14:19:05 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:05 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:05 volumio volumio[5680]: error: updateQueue error: null Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand status took 27 milliseconds Jun 13 14:19:05 volumio volumio[5680]: info: ------------------------------ 26ms Jun 13 14:19:05 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:05 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:05 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:05 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:05 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:05 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:05 volumio volumio[5680]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 13 14:19:05 volumio volumio[5680]: info: ------------------------------ 41ms Jun 13 14:19:05 volumio volumio[5680]: info: ------------------------------ 46ms Jun 13 14:19:07 volumio volumio[5680]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1795 milliseconds Jun 13 14:19:07 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 14:19:07 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand play Jun 13 14:19:07 volumio volumio[5680]: info: Jun 13 14:19:07 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:07 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:07 volumio volumio[5680]: info: ------------------------------ 1783ms Jun 13 14:19:07 volumio volumio[5680]: info: ------------------------------ 1782ms Jun 13 14:19:07 volumio volumio[5680]: info: Jun 13 14:19:07 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:07 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:07 volumio volumio[5680]: info: Jun 13 14:19:07 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:07 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:07 volumio volumio[5680]: info: sendMpdCommand play took 4 milliseconds Jun 13 14:19:07 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:07 volumio volumio[5680]: info: ------------------------------ 2ms Jun 13 14:19:07 volumio volumio[5680]: info: ------------------------------ 2ms Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::startPlaybackTimer Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: info: [1749795549551] ControllerWebradio::clearAddPlayTrack Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand stop took 1 milliseconds Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:09 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand clear took 1 milliseconds Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:09 volumio volumio[5680]: error: updateQueue error: null Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 13ms Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 298 milliseconds Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand play Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:09 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand status took 299 milliseconds Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand status took 298 milliseconds Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:09 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: 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} Jun 13 14:19:09 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::syncState stateService stop Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: No code Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:09 volumio volumio[5680]: 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} Jun 13 14:19:09 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::syncState stateService stop Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: No code Jun 13 14:19:09 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:09 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 337ms Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 338ms Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:09 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:09 volumio volumio[5680]: info: Jun 13 14:19:09 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:09 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:09 volumio volumio[5680]: info: sendMpdCommand play took 42 milliseconds Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 42ms Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 7ms Jun 13 14:19:09 volumio volumio[5680]: info: ------------------------------ 6ms Jun 13 14:19:12 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:12 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:12 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:12 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:12 volumio volumio[5680]: info: CoreStateMachine::startPlaybackTimer Jun 13 14:19:12 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:12 volumio volumio[5680]: info: [1749795552602] ControllerWebradio::clearAddPlayTrack Jun 13 14:19:12 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand stop took 1411 milliseconds Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand clear took 4 milliseconds Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:14 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:14 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:14 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:14 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 31ms Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 29ms Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: error: updateQueue error: null Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 32ms Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 299 milliseconds Jun 13 14:19:14 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 14:19:14 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand play Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 273ms Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 272ms Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: info: Jun 13 14:19:14 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:14 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:14 volumio volumio[5680]: info: sendMpdCommand play took 4 milliseconds Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 2ms Jun 13 14:19:14 volumio volumio[5680]: info: ------------------------------ 1ms Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::startPlaybackTimer Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: info: [1749795555197] ControllerWebradio::clearAddPlayTrack Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand stop took 1 milliseconds Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:15 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand clear took 1 milliseconds Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:15 volumio volumio[5680]: error: updateQueue error: null Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 14ms Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 293 milliseconds Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand play Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:15 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand status took 294 milliseconds Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand status took 293 milliseconds Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:15 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: 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} Jun 13 14:19:15 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::syncState stateService stop Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: No code Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:15 volumio volumio[5680]: 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} Jun 13 14:19:15 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::syncState stateService stop Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: No code Jun 13 14:19:15 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:15 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 329ms Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:15 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:15 volumio volumio[5680]: info: Jun 13 14:19:15 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:15 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:15 volumio volumio[5680]: info: sendMpdCommand play took 39 milliseconds Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 39ms Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:15 volumio volumio[5680]: info: ------------------------------ 2ms Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 1 milliseconds Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: Jun 13 14:19:22 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand status took 1 milliseconds Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:22 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:22 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: ------------------------------ 26ms Jun 13 14:19:22 volumio volumio[5680]: info: ------------------------------ 38ms Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 13 14:19:22 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 13 14:19:22 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:22 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:22 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: 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":"antenne","artist":"(#1 - 5228/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Jun 13 14:19:22 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:22 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:22 volumio volumio[5680]: info: ------------------------------ 89ms Jun 13 14:19:22 volumio volumio[5680]: info: ------------------------------ 87ms Jun 13 14:19:22 volumio volumio[5680]: info: ------------------------------ 86ms Jun 13 14:19:22 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:22 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:22 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Queue 9 is active on fifo 3 and stuck for 10000 ms. SW [0, 1] HW [0, 1] FH TRB=0x080309000 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Microcode SW error detected. Restarting 0x2000000. Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Start IWL Error Log Dump: Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Transport status: 0x0000004A, valid: 6 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Loaded firmware version: 29.198743027.0 3168-29.ucode Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x008002F1 | trm_hw_status0 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00043D6C | branchlink2 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x0004AFA2 | interruptlink1 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x0004FD7C | interruptlink2 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | data1 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000080 | data2 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x07030000 | data3 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | beacon time Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x07216346 | tsf low Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | tsf hi Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | time gp1 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x07216347 | time gp2 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x0000001D | uCode version major Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x0BD893F3 | uCode version minor Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000220 | hw version Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00C89200 | board version Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x0900001C | hcmd Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00122048 | isr0 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | isr1 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000002 | isr2 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x004164C1 | isr3 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | isr4 Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x002B0103 | last cmd Id Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | wait_event Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_control Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000820 | l2p_duration Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_mhvalid Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_addr_match Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000007 | lmpm_pmg_sel Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x14100601 | timestamp Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00343038 | flow_handler Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Fseq Registers: Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_ERROR_CODE Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_TOP_INIT_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_CNVIO_INIT_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_OTP_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_TOP_CONTENT_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_ALIVE_TOKEN Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_CNVI_ID Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_CNVR_ID Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | CNVI_AUX_MISC_CHIP Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | CNVR_AUX_MISC_CHIP Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_PREV_CNVIO_INIT_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_WIFI_FSEQ_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_BT_FSEQ_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: 0x00000000 | FSEQ_CLASS_TP_VERSION Jun 13 14:19:24 volumio kernel: iwlwifi 0000:02:00.0: Collecting data: trigger 2 fired. Jun 13 14:19:24 volumio kernel: ieee80211 phy0: Hardware restart was requested Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 14ms Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 13ms Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 12ms Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 2ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 1 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 9ms Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 8ms Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 7ms Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:24 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:24 volumio volumio[5680]: info: Jun 13 14:19:24 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 5 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 3 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:24 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:24 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:24 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:24 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:24 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:25 volumio volumio[5680]: info: ------------------------------ 16ms Jun 13 14:19:25 volumio volumio[5680]: info: ------------------------------ 14ms Jun 13 14:19:25 volumio volumio[5680]: info: ------------------------------ 12ms Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:29 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:29 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:29 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:29 volumio volumio[5680]: info: Jun 13 14:19:29 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 6ms Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand status took 6 milliseconds Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 3ms Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:29 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:29 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:29 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:29 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:29 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:29 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 11ms Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 9ms Jun 13 14:19:29 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:29 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:29 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:29 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:29 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:29 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:29 volumio volumio[5680]: info: ------------------------------ 9ms Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:31 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:31 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:31 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:31 volumio volumio[5680]: info: Jun 13 14:19:31 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 6ms Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand status took 6 milliseconds Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand status took 6 milliseconds Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:31 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:31 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:31 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:31 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:31 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:31 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 14ms Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 12ms Jun 13 14:19:31 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 13 14:19:31 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:31 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:31 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:31 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:31 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:31 volumio volumio[5680]: info: ------------------------------ 15ms Jun 13 14:19:32 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::ClearQueue Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::stop Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::stPlaybackTimer Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::updateTrackBlock Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::getTrackBlock Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::serviceStop Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::serviceStop Jun 13 14:19:32 volumio volumio[5680]: info: [1749795572990] ControllerWebradio::stop Jun 13 14:19:32 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::clearPlayQueue Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::saveQueue Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::volumioPushQueue Jun 13 14:19:32 volumio volumio[5680]: info: CoreStateMachine::addQueueItems Jun 13 14:19:32 volumio volumio[5680]: info: CorePlayQueue::addQueueItems Jun 13 14:19:32 volumio volumio[5680]: info: Preload queue cleared Jun 13 14:19:32 volumio volumio[5680]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960 Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 13 14:19:32 volumio volumio[5680]: info: CoreCommandRouter::volumioPushQueue Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::saveQueue Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::updateTrackBlock Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrackBlock Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::volumioPlay Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::play index 0 Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::stop Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::play index undefined Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::startPlaybackTimer Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:33 volumio volumio[5680]: info: [1749795573006] ControllerWebradio::clearAddPlayTrack Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand stop Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand stop took 23 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:33 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand stop took 8 milliseconds Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand clear Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:33 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:33 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand status took 7 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand clear took 8 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand status took 6 milliseconds Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:33 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:33 volumio volumio[5680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 26ms Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: error: updateQueue error: null Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand status took 297 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 296ms Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 295 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" took 294 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 275ms Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 14:19:33 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand play Jun 13 14:19:33 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:33 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:33 volumio volumio[5680]: 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} Jun 13 14:19:33 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::syncState stateService stop Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:33 volumio volumio[5680]: info: No code Jun 13 14:19:33 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:33 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:33 volumio volumio[5680]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 340ms Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 344ms Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 46ms Jun 13 14:19:33 volumio volumio[5680]: info: sendMpdCommand play took 44 milliseconds Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 5ms Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:33 volumio volumio[5680]: info: Jun 13 14:19:33 volumio volumio[5680]: ---------------------------- MPD announces system playlist update Jun 13 14:19:33 volumio volumio[5680]: info: Ignoring MPD Status Update Jun 13 14:19:33 volumio volumio[5680]: info: ------------------------------ 4ms Jun 13 14:19:35 volumio nmbd[979]: [2025/06/13 14:19:35.965789, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 13 14:19:35 volumio nmbd[979]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.34 for name WORKGROUP<1d>. Jun 13 14:19:35 volumio nmbd[979]: This response was from IP 192.168.3.33, reporting an IP address of 192.168.3.33. Jun 13 14:19:36 volumio volumio[5680]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%205228%2F500000)%20ANTENNE%20BAYERN/9a863013-bdd8-4146-b0a2-f86e0798b276.jpg' Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: Jun 13 14:19:38 volumio volumio[5680]: ---------------------------- MPD announces state update: player Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::getState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand status Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 4 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand status took 2 milliseconds Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseState Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus stop Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:38 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:38 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 22ms Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 48ms Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 47ms Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 13 14:19:38 volumio volumio[5680]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 13 14:19:38 volumio volumio[5680]: verbose: ControllerMpd::parseTrackInfo Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:38 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:38 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: ControllerMpd::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::servicePushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: 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":"chillout","artist":"(#1 - 1000/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Jun 13 14:19:38 volumio volumio[5680]: verbose: CURRENT POSITION 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState stateService play Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::syncState currentStatus play Jun 13 14:19:38 volumio volumio[5680]: info: Received an update from plugin. extracting info from payload Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CorePlayQueue::getTrack 0 Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreStateMachine::pushState Jun 13 14:19:38 volumio volumio[5680]: info: CoreCommandRouter::volumioPushState Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 111ms Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 108ms Jun 13 14:19:38 volumio volumio[5680]: info: ------------------------------ 108ms Jun 13 14:19:45 volumio volumio[5680]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 14:19:45 volumio volumio[5680]: Error: connect ETIMEDOUT 168.143.162.42:80 Jun 13 14:19:45 volumio volumio[5680]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 13 14:19:45 volumio volumio[5680]: errno: -110, Jun 13 14:19:45 volumio volumio[5680]: code: 'ETIMEDOUT', Jun 13 14:19:45 volumio volumio[5680]: syscall: 'connect', Jun 13 14:19:45 volumio volumio[5680]: address: '168.143.162.42', Jun 13 14:19:45 volumio volumio[5680]: port: 80 Jun 13 14:19:45 volumio volumio[5680]: } Jun 13 14:19:45 volumio volumio[5680]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 14:19:45 volumio sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-13 14:18 Jun 13 14:19:45 volumio sudo[6221]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"