Mar 26 22:27:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 22:27:04 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 22:27:08 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 22:27:12 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 22:27:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 22:27:18 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Mar 26 22:27:18 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 26 22:27:33 volumio volumio[1284]: info: Received Get System Version Mar 26 22:27:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 22:27:44 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Mar 26 22:27:44 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:27:48 volumio volumio[1284]: info: CoreCommandRouter::volumioGetQueue Mar 26 22:27:48 volumio volumio[1284]: info: CoreStateMachine::getQueue Mar 26 22:27:48 volumio volumio[1284]: info: CorePlayQueue::getQueue Mar 26 22:27:52 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 26 22:27:52 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 22:27:53 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:27:55 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 26 22:27:56 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:27:59 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:27:59 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:27:59 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:27:59 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:27:59 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 26 22:27:59 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:27:59 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:27:59 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:27:59 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:27:59 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:27:59 volumio volumio[1284]: info: [1774546079463] ControllerWebradio::clearAddPlayTrack Mar 26 22:27:59 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:27:59 volumio volumio[1284]: info: sendMpdCommand stop took 11 milliseconds Mar 26 22:27:59 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:27:59 volumio volumio[1284]: info: Mar 26 22:27:59 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:27:59 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:27:59 volumio volumio[1284]: info: sendMpdCommand clear took 3 milliseconds Mar 26 22:27:59 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 26 22:27:59 volumio volumio[1284]: info: Mar 26 22:27:59 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:27:59 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:27:59 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:27:59 volumio volumio[1284]: info: ------------------------------ 7ms Mar 26 22:28:03 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:03 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:03 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:03 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:03 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 26 22:28:03 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:03 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:03 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:03 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:03 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:03 volumio volumio[1284]: info: [1774546083839] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:03 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:05 volumio volumio[1284]: info: Mar 26 22:28:05 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:05 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:05 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 5556 milliseconds Mar 26 22:28:05 volumio volumio[1284]: info: ------------------------------ 5555ms Mar 26 22:28:05 volumio volumio[1284]: info: sendMpdCommand stop took 1196 milliseconds Mar 26 22:28:05 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:05 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:05 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:05 volumio volumio[1284]: info: Mar 26 22:28:05 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:05 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:05 volumio volumio[1284]: info: ------------------------------ 9ms Mar 26 22:28:05 volumio volumio[1284]: info: sendMpdCommand play took 4 milliseconds Mar 26 22:28:07 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:07 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:07 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:07 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:07 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:07 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:07 volumio volumio[1284]: info: [1774546087873] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:07 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:10 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:10 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:10 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:10 volumio volumio[1284]: info: [1774546090228] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:10 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:10 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:10 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:10 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:10 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:10 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:10 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:10 volumio volumio[1284]: info: [1774546090475] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:10 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:12 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:12 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:12 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166 Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:12 volumio volumio[1284]: info: [1774546092584] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:12 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:12 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:12 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:12 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166 Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:12 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:12 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:12 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:12 volumio volumio[1284]: info: [1774546092858] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:12 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:15 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:15 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 10382 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 10382ms Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand stop took 7550 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand stop took 5195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand stop took 4949 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand stop took 2839 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand stop took 2566 milliseconds Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:15 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:15 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:15 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 13ms Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand status took 18 milliseconds Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:15 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:15 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:15 volumio volumio[1284]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 35ms Mar 26 22:28:15 volumio systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Mar 26 22:28:15 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:15 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand clear took 195 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 194ms Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand status took 192 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:15 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:15 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:15 volumio volumio[1284]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 22:28:15 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::syncState stateService stop Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus stop Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:15 volumio volumio[1284]: info: No code Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:15 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:15 volumio volumio5-onboarding[2116]: time=2026-03-26T22:28:15.669+05:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 26 22:28:15 volumio volumio5-onboarding[2116]: time=2026-03-26T22:28:15.675+05:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 26 22:28:15 volumio systemd[1]: fstrim.service: Deactivated successfully. Mar 26 22:28:15 volumio systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Mar 26 22:28:15 volumio volumio5-onboarding[2116]: time=2026-03-26T22:28:15.677+05:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 259ms Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:15 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:15 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:28:15 volumio volumio[1284]: info: ------------------------------ 71ms Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand play took 68 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: Mar 26 22:28:15 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:15 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:15 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 184 milliseconds Mar 26 22:28:15 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:15 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:16 volumio volumio[1284]: info: Mar 26 22:28:16 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:16 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1323 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1320 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" took 1320 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" took 1319 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: ------------------------------ 1258ms Mar 26 22:28:16 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:16 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:16 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:16 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:16 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:16 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:16 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:16 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:16 volumio volumio[1284]: info: Mar 26 22:28:16 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:16 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:16 volumio volumio[1284]: info: ------------------------------ 1146ms Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand play took 1146 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: ------------------------------ 7ms Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand play took 5 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand play took 5 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand play took 5 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: sendMpdCommand play took 5 milliseconds Mar 26 22:28:16 volumio volumio[1284]: info: ------------------------------ 5ms Mar 26 22:28:16 volumio volumio[1284]: info: Mar 26 22:28:16 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:16 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:16 volumio volumio[1284]: info: ------------------------------ 4ms Mar 26 22:28:18 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::ClearQueue Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::clearPlayQueue Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::addQueueItems Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::addQueueItems Mar 26 22:28:18 volumio volumio[1284]: info: Preload queue cleared Mar 26 22:28:18 volumio volumio[1284]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::volumioPushQueue Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::saveQueue Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::volumioPlay Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::play index 0 Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::stop Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::play index undefined Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::startPlaybackTimer Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:18 volumio volumio[1284]: info: [1774546098438] ControllerWebradio::clearAddPlayTrack Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand stop took 20 milliseconds Mar 26 22:28:18 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:18 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:18 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand status took 4 milliseconds Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand clear took 4 milliseconds Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand status took 3 milliseconds Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:18 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 22:28:18 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:18 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:18 volumio volumio[1284]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 25ms Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:18 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:18 volumio volumio[1284]: error: updateQueue error: null Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 26ms Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:18 volumio volumio[1284]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 34ms Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 178 milliseconds Mar 26 22:28:18 volumio volumio[1284]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 22:28:18 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:18 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 162ms Mar 26 22:28:18 volumio volumio[1284]: info: Mar 26 22:28:18 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:18 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:18 volumio volumio[1284]: info: sendMpdCommand play took 4 milliseconds Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 4ms Mar 26 22:28:18 volumio volumio[1284]: info: ------------------------------ 3ms Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 6 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 5 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 10 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 7 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus stop Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 34ms Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 45ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 86ms Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 85ms Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:20 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:20 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:20 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 8ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 6 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 4ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 4 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces system playlist update Mar 26 22:28:20 volumio volumio[1284]: info: Ignoring MPD Status Update Mar 26 22:28:20 volumio volumio[1284]: info: Mar 26 22:28:20 volumio volumio[1284]: ---------------------------- MPD announces state update: player Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::getState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 12ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 27 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 24ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand status took 23 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseState Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 79ms Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 76ms Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 26 22:28:20 volumio volumio[1284]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: verbose: ControllerMpd::parseTrackInfo Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ControllerMpd::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 26 22:28:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Mar 26 22:28:20 volumio volumio[1284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 22:28:20 volumio volumio[1284]: verbose: CURRENT POSITION 0 Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState stateService play Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::syncState currentStatus play Mar 26 22:28:20 volumio volumio[1284]: info: Received an update from plugin. extracting info from payload Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 26 22:28:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 128ms Mar 26 22:28:20 volumio volumio[1284]: info: ------------------------------ 123ms Mar 26 22:28:22 volumio volumio[1284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 22:28:22 volumio volumio[1284]: TypeError: Cannot read properties of null (reading 'slice') Mar 26 22:28:22 volumio volumio[1284]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 26 22:28:22 volumio volumio[1284]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 26 22:28:22 volumio volumio[1284]: at Object.onceWrapper (node:events:629:26) Mar 26 22:28:22 volumio volumio[1284]: at ClientRequest.emit (node:events:514:28) Mar 26 22:28:22 volumio volumio[1284]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 26 22:28:22 volumio volumio[1284]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 26 22:28:22 volumio volumio[1284]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 26 22:28:22 volumio volumio[1284]: at TLSSocket.emit (node:events:514:28) Mar 26 22:28:22 volumio volumio[1284]: at addChunk (node:internal/streams/readable:343:12) Mar 26 22:28:22 volumio volumio[1284]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 26 22:28:22 volumio volumio[1284]: at Readable.push (node:internal/streams/readable:253:10) Mar 26 22:28:22 volumio volumio[1284]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 26 22:28:22 volumio volumio[1284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 22:28:22 volumio sudo[3018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 22:27' Mar 26 22:28:22 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"