Oct 14 11:32:17 volumio volumio[2375]: info: VolumeController::SetAlsaVolume80 Oct 14 11:32:17 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:17 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:17 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:18 volumio volumio[2375]: info: VolumeController::SetAlsaVolume97 Oct 14 11:32:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:18 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:22 volumio volumio[2375]: info: VolumeController::SetAlsaVolume82 Oct 14 11:32:22 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:22 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:22 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:24 volumio volumio[2375]: info: VolumeController::SetAlsaVolume90 Oct 14 11:32:24 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:24 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:24 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:28 volumio volumio[2375]: info: VolumeController::SetAlsaVolume100 Oct 14 11:32:28 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:28 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:28 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:30 volumio volumio[2375]: info: VolumeController::SetAlsaVolume70 Oct 14 11:32:30 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:30 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:30 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:30 volumio volumio[2375]: info: VolumeController::SetAlsaVolume67 Oct 14 11:32:30 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:30 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:30 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::volumioStop Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::stPlaybackTimer Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::serviceStop Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::serviceStop Oct 14 11:32:39 volumio volumio[2375]: info: [1791977559465] ControllerWebradio::stop Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:39 volumio volumio[2375]: info: Oct 14 11:32:39 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:39 volumio volumio[2375]: info: sendMpdCommand stop took 31 milliseconds Oct 14 11:32:39 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:39 volumio volumio[2375]: info: Oct 14 11:32:39 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:39 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:39 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:32:39 volumio volumio[2375]: info: sendMpdCommand status took 1 milliseconds Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:39 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:39 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 14 11:32:39 volumio volumio[2375]: info: ------------------------------ 19ms Oct 14 11:32:39 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 14 11:32:39 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:39 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:39 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:39 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:39 volumio volumio[2375]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 14 11:32:39 volumio volumio[2375]: info: ------------------------------ 35ms Oct 14 11:32:41 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:41 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:41 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:41 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:41 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:41 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:41 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:41 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:41 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:41 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:41 volumio volumio[2375]: info: [1791977561838] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:41 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:41 volumio volumio[2375]: info: sendMpdCommand stop took 4 milliseconds Oct 14 11:32:41 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:41 volumio volumio[2375]: info: Oct 14 11:32:41 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:41 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:41 volumio volumio[2375]: info: sendMpdCommand clear took 1 milliseconds Oct 14 11:32:41 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:41 volumio volumio[2375]: info: Oct 14 11:32:41 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:41 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:41 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:41 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:32:42 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:42 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 763 milliseconds Oct 14 11:32:42 volumio volumio[2375]: info: ------------------------------ 763ms Oct 14 11:32:42 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:42 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:42 volumio volumio[2375]: info: sendMpdCommand play took 2 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: [1791977565208] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand stop took 3 milliseconds Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand clear took 0 milliseconds Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:45 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:45 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 287 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: ------------------------------ 288ms Oct 14 11:32:45 volumio volumio[2375]: info: ------------------------------ 287ms Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand play took 1 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: [1791977565607] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand stop took 8 milliseconds Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand clear took 3 milliseconds Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:45 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:45 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:45 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:45 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:45 volumio volumio[2375]: info: [1791977565856] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:45 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:45 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:45 volumio volumio[2375]: info: ------------------------------ 283ms Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 282 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: ------------------------------ 280ms Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand stop took 45 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand play took 1 milliseconds Oct 14 11:32:45 volumio volumio[2375]: info: sendMpdCommand clear took 1 milliseconds Oct 14 11:32:45 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:45 volumio volumio[2375]: info: Oct 14 11:32:45 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:45 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:46 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:46 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:46 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 295 milliseconds Oct 14 11:32:46 volumio volumio[2375]: info: ------------------------------ 294ms Oct 14 11:32:46 volumio volumio[2375]: info: ------------------------------ 293ms Oct 14 11:32:46 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:46 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:46 volumio volumio[2375]: info: sendMpdCommand play took 2 milliseconds Oct 14 11:32:54 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:54 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:54 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:54 volumio volumio[2375]: info: [1791977574261] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand stop took 12 milliseconds Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:54 volumio volumio[2375]: info: Oct 14 11:32:54 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:54 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand clear took 3 milliseconds Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:54 volumio volumio[2375]: info: Oct 14 11:32:54 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:54 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:54 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:54 volumio volumio[2375]: info: ------------------------------ 4ms Oct 14 11:32:54 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:54 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:54 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346 Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:54 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:54 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:54 volumio volumio[2375]: info: [1791977574569] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:54 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 298 milliseconds Oct 14 11:32:54 volumio volumio[2375]: info: ------------------------------ 297ms Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand stop took 6 milliseconds Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand play took 1 milliseconds Oct 14 11:32:54 volumio volumio[2375]: info: Oct 14 11:32:54 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:54 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand clear took 2 milliseconds Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" Oct 14 11:32:54 volumio volumio[2375]: info: Oct 14 11:32:54 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:54 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:54 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:54 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:54 volumio volumio[2375]: info: ------------------------------ 284ms Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=100325346" took 283 milliseconds Oct 14 11:32:54 volumio volumio[2375]: info: ------------------------------ 283ms Oct 14 11:32:54 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:54 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:54 volumio volumio[2375]: info: sendMpdCommand play took 2 milliseconds Oct 14 11:32:56 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:56 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:56 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:32:56 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:32:56 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687 Oct 14 11:32:56 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:32:56 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:32:56 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:32:56 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:56 volumio volumio[2375]: info: [1791977576372] ControllerWebradio::clearAddPlayTrack Oct 14 11:32:56 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:32:56 volumio volumio[2375]: info: sendMpdCommand stop took 6 milliseconds Oct 14 11:32:56 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:32:56 volumio volumio[2375]: info: Oct 14 11:32:56 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:56 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:56 volumio volumio[2375]: info: sendMpdCommand clear took 1 milliseconds Oct 14 11:32:56 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" Oct 14 11:32:56 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:32:56 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:32:56 volumio volumio[2375]: info: Oct 14 11:32:56 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:56 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:56 volumio volumio[2375]: info: Oct 14 11:32:56 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:56 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:56 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" took 282 milliseconds Oct 14 11:32:56 volumio volumio[2375]: info: ------------------------------ 281ms Oct 14 11:32:56 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:32:56 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:32:56 volumio volumio[2375]: info: Oct 14 11:32:56 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:56 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:56 volumio volumio[2375]: info: ------------------------------ 4ms Oct 14 11:32:56 volumio volumio[2375]: info: sendMpdCommand play took 3 milliseconds Oct 14 11:32:56 volumio volumio[2375]: info: ------------------------------ 3ms Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: 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":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus stop Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 10ms Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 4 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: 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":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:32:57 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: 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":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:32:57 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 64ms Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 61ms Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: 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":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:32:57 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 93ms Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:57 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:32:57 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:32:57 volumio volumio[2375]: info: Oct 14 11:32:57 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 3ms Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Why Not · What Can We Do (1995)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:32:57 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 38ms Oct 14 11:32:57 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 14 11:32:57 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:32:57 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:32:57 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:32:57 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Why Not · What Can We Do (1995)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Oct 14 11:32:57 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:32:57 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:32:57 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:32:57 volumio volumio[2375]: info: ------------------------------ 61ms Oct 14 11:33:06 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::stPlaybackTimer Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::serviceStop Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::serviceStop Oct 14 11:33:06 volumio volumio[2375]: info: [1791977586131] ControllerWebradio::stop Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:33:06 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:06 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99619743 Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: [1791977586162] ControllerWebradio::clearAddPlayTrack Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand stop took 37 milliseconds Oct 14 11:33:06 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand stop took 6 milliseconds Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:06 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:06 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand clear took 2 milliseconds Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99619743" Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:06 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:06 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:06 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:06 volumio volumio[2375]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 12ms Oct 14 11:33:06 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 13ms Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:06 volumio volumio[2375]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 17ms Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:06 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99619743" took 280 milliseconds Oct 14 11:33:06 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:33:06 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:06 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 272ms Oct 14 11:33:06 volumio volumio[2375]: info: Oct 14 11:33:06 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:06 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:06 volumio volumio[2375]: info: sendMpdCommand play took 8 milliseconds Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 6ms Oct 14 11:33:06 volumio volumio[2375]: info: ------------------------------ 5ms Oct 14 11:33:07 volumio volumio[2375]: info: Oct 14 11:33:07 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:07 volumio volumio[2375]: info: Oct 14 11:33:07 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:07 volumio volumio[2375]: info: Oct 14 11:33:07 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand status took 25 milliseconds Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand status took 25 milliseconds Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:07 volumio volumio[2375]: info: Oct 14 11:33:07 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: 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":"megadance","artist":"(#1 - 17649/512) MEGA DANCE - amgradio.ru","album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:07 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus stop Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: 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":"megadance","artist":"(#1 - 17649/512) MEGA DANCE - amgradio.ru","album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:07 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:07 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: ------------------------------ 42ms Oct 14 11:33:07 volumio volumio[2375]: info: ------------------------------ 52ms Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand status took 28 milliseconds Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: 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":"megadance","artist":"(#1 - 17649/512) MEGA DANCE - amgradio.ru","album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:07 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:07 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: ------------------------------ 52ms Oct 14 11:33:07 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 14 11:33:07 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:07 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: 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":"megadance","artist":"(#1 - 17649/512) MEGA DANCE - amgradio.ru","album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:07 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:07 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:07 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:07 volumio volumio[2375]: info: ------------------------------ 92ms Oct 14 11:33:08 volumio volumio[2375]: info: Oct 14 11:33:08 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:08 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:08 volumio volumio[2375]: info: Oct 14 11:33:08 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:08 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:08 volumio volumio[2375]: info: Oct 14 11:33:08 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:08 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:08 volumio volumio[2375]: info: Oct 14 11:33:08 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:08 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:08 volumio volumio[2375]: info: ------------------------------ 4ms Oct 14 11:33:08 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:08 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:08 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:08 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:08 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:08 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:08 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KILIMANJARO / BLOODY CIVILIAN / THE JOY - I DON’T WANT TO GO","artist":null,"album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:08 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:08 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:08 volumio volumio[2375]: info: ------------------------------ 21ms Oct 14 11:33:08 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 14 11:33:08 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:08 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:08 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:08 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KILIMANJARO / BLOODY CIVILIAN / THE JOY - I DON’T WANT TO GO","artist":null,"album":null,"uri":"http://shout.amgradio.ru:80/megadance","trackType":""} Oct 14 11:33:08 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:08 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:08 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:08 volumio volumio[2375]: info: ------------------------------ 41ms Oct 14 11:33:10 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::stPlaybackTimer Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::serviceStop Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::serviceStop Oct 14 11:33:10 volumio volumio[2375]: info: [1791977590043] ControllerWebradio::stop Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:33:10 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:10 volumio volumio[2375]: info: Adding Item to queue: http://stream-relay-geo.ntslive.net/stream2 Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: [1791977590059] ControllerWebradio::clearAddPlayTrack Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:10 volumio volumio[2375]: info: Oct 14 11:33:10 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand stop took 24 milliseconds Oct 14 11:33:10 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand stop took 8 milliseconds Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:33:10 volumio volumio[2375]: info: Oct 14 11:33:10 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:10 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:10 volumio volumio[2375]: info: Oct 14 11:33:10 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:10 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand status took 5 milliseconds Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand clear took 5 milliseconds Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://stream-relay-geo.ntslive.net/stream2" Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:10 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:10 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:10 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:10 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:10 volumio volumio[2375]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 14 11:33:10 volumio volumio[2375]: info: ------------------------------ 16ms Oct 14 11:33:10 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:33:10 volumio volumio[2375]: info: ------------------------------ 14ms Oct 14 11:33:10 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 14 11:33:10 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:10 volumio volumio[2375]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 11:33:10 volumio volumio[2375]: info: ------------------------------ 20ms Oct 14 11:33:10 volumio volumio[2375]: info: Oct 14 11:33:10 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:10 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:13 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" Oct 14 11:33:13 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:33:13 volumio volumio[2375]: info: ------------------------------ 3102ms Oct 14 11:33:13 volumio volumio[2375]: info: Oct 14 11:33:13 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:13 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:13 volumio volumio[2375]: info: sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" took 1 milliseconds Oct 14 11:33:13 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:33:13 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:33:13 volumio volumio[2375]: info: Oct 14 11:33:13 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:13 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:13 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:13 volumio volumio[2375]: info: sendMpdCommand play took 2 milliseconds Oct 14 11:33:13 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:18 volumio volumio[2375]: info: Oct 14 11:33:18 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:18 volumio volumio[2375]: info: Oct 14 11:33:18 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:18 volumio volumio[2375]: info: Oct 14 11:33:18 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand status took 7 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:18 volumio volumio[2375]: info: Oct 14 11:33:18 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand status took 3 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Oct 14 11:33:18 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus stop Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Oct 14 11:33:18 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:18 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: ------------------------------ 20ms Oct 14 11:33:18 volumio volumio[2375]: info: ------------------------------ 28ms Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 11:33:18 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:18 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Oct 14 11:33:18 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:18 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Oct 14 11:33:18 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:18 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:18 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:18 volumio volumio[2375]: info: ------------------------------ 72ms Oct 14 11:33:18 volumio volumio[2375]: info: ------------------------------ 71ms Oct 14 11:33:19 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::ClearQueue Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::stPlaybackTimer Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::serviceStop Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::serviceStop Oct 14 11:33:19 volumio volumio[2375]: info: [1791977599135] ControllerWebradio::stop Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::clearPlayQueue Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::addQueueItems Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::addQueueItems Oct 14 11:33:19 volumio volumio[2375]: info: Preload queue cleared Oct 14 11:33:19 volumio volumio[2375]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=453423 Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioPushQueue Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::saveQueue Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::updateTrackBlock Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrackBlock Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioPlay Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::play index 0 Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::stop Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::play index undefined Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: [1791977599161] ControllerWebradio::clearAddPlayTrack Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:33:19 volumio volumio[2375]: info: Oct 14 11:33:19 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:19 volumio volumio[2375]: info: sendMpdCommand stop took 34 milliseconds Oct 14 11:33:19 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:19 volumio volumio[2375]: info: sendMpdCommand stop took 8 milliseconds Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:33:19 volumio volumio[2375]: info: Oct 14 11:33:19 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:19 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:19 volumio volumio[2375]: info: Oct 14 11:33:19 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:19 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:19 volumio volumio[2375]: info: sendMpdCommand status took 3 milliseconds Oct 14 11:33:19 volumio volumio[2375]: info: sendMpdCommand clear took 2 milliseconds Oct 14 11:33:19 volumio volumio[2375]: info: sendMpdCommand status took 1 milliseconds Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=453423" Oct 14 11:33:19 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:19 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:19 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:19 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:19 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:19 volumio volumio[2375]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 14 11:33:19 volumio volumio[2375]: info: ------------------------------ 16ms Oct 14 11:33:19 volumio volumio[2375]: info: Oct 14 11:33:19 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:19 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:19 volumio volumio[2375]: error: updateQueue error: null Oct 14 11:33:19 volumio volumio[2375]: info: ------------------------------ 16ms Oct 14 11:33:20 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 837 milliseconds Oct 14 11:33:20 volumio volumio[2375]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=453423" took 837 milliseconds Oct 14 11:33:20 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:20 volumio volumio[2375]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:33:20 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:33:20 volumio volumio[2375]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 11:33:20 volumio volumio[2375]: info: ------------------------------ 843ms Oct 14 11:33:20 volumio volumio[2375]: info: Oct 14 11:33:20 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:20 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:20 volumio volumio[2375]: info: ------------------------------ 825ms Oct 14 11:33:20 volumio volumio[2375]: info: Oct 14 11:33:20 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:20 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:20 volumio volumio[2375]: info: sendMpdCommand play took 3 milliseconds Oct 14 11:33:20 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:20 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:20 volumio volumio[2375]: info: Received Get System Info Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:33:20 volumio volumio[2375]: info: Discovery: Getting this device information Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::volumioGetState Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 11:33:20 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 11:33:21 volumio volumio5-onboarding[2084]: time=2026-10-14T11:33:21.111Z level=INFO msg="service successfully established" component=discovery/localnet Oct 14 11:33:25 volumio volumio[2375]: info: Oct 14 11:33:25 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:25 volumio volumio[2375]: info: Oct 14 11:33:25 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:25 volumio volumio[2375]: info: Oct 14 11:33:25 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand status took 4 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:25 volumio volumio[2375]: info: Oct 14 11:33:25 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand status took 3 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 855/2000) RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:25 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus stop Oct 14 11:33:25 volumio volumio[2375]: info: ------------------------------ 11ms Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:25 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:25 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 855/2000) RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:25 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:25 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 855/2000) RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:25 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:25 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 855/2000) RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:25 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:25 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:25 volumio volumio[2375]: info: ------------------------------ 57ms Oct 14 11:33:25 volumio volumio[2375]: info: ------------------------------ 54ms Oct 14 11:33:25 volumio volumio[2375]: info: ------------------------------ 53ms Oct 14 11:33:25 volumio volumio[2375]: info: CoreCommandRouter::volumioGetState Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:26 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:26 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 8ms Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand status took 7 milliseconds Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 7ms Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:26 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:26 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:26 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:26 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 54ms Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 54ms Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:26 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces system playlist update Oct 14 11:33:26 volumio volumio[2375]: info: Ignoring MPD Status Update Oct 14 11:33:26 volumio volumio[2375]: info: Oct 14 11:33:26 volumio volumio[2375]: ---------------------------- MPD announces state update: player Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::getState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 2ms Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand status took 3 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 3ms Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand status took 2 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseState Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nicolae Guta Play Aj - Bem","artist":"RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:26 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:26 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 24ms Oct 14 11:33:26 volumio volumio[2375]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 14 11:33:26 volumio volumio[2375]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:33:26 volumio volumio[2375]: info: ControllerMpd::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::servicePushState Oct 14 11:33:26 volumio volumio[2375]: info: CorePlayQueue::getTrack 0 Oct 14 11:33:26 volumio volumio[2375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nicolae Guta Play Aj - Bem","artist":"RADIO 1 MANELE ROMANIA - wWw.RadioUnuManele.CoM HOSTAT de HostClean.Ro","album":null,"uri":"http://54.39.28.158:8000/stream","trackType":""} Oct 14 11:33:26 volumio volumio[2375]: verbose: CURRENT POSITION 0 Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState stateService play Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:33:26 volumio volumio[2375]: info: Received an update from plugin. extracting info from payload Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreStateMachine::pushState Oct 14 11:33:26 volumio volumio[2375]: info: CoreCommandRouter::volumioPushState Oct 14 11:33:26 volumio volumio[2375]: info: ------------------------------ 59ms Oct 14 11:33:32 volumio volumio[2375]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:33:32 volumio volumio[2375]: Error: socket hang up Oct 14 11:33:32 volumio volumio[2375]: at connResetException (node:internal/errors:720:14) Oct 14 11:33:32 volumio volumio[2375]: at TLSSocket.socketOnEnd (node:_http_client:519:23) Oct 14 11:33:32 volumio volumio[2375]: at TLSSocket.emit (node:events:526:35) Oct 14 11:33:32 volumio volumio[2375]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 14 11:33:32 volumio volumio[2375]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 14 11:33:32 volumio volumio[2375]: code: 'ECONNRESET' Oct 14 11:33:32 volumio volumio[2375]: } Oct 14 11:33:32 volumio volumio[2375]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:33:32 volumio sudo[7066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 11:32' Oct 14 11:33:32 volumio sudo[7066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"