Feb 26 15:55:24 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::stPlaybackTimer Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::serviceStop Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::serviceStop Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::stop Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Feb 26 15:55:24 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:55:24 volumio volumio[1136]: info: Adding Item to queue: webrp/13 Feb 26 15:55:24 volumio volumio[1136]: info: Exploding uri webrp/13 in service radio_paradise Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::play index 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::play index undefined Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: [RadioParadise] Matched station: Radio Paradise Beyond...(FLAC), chan=5 Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand stop took 21 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand stop took 7 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10441.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Out Of Touch","title":"Out Of Touch","artist":"Lucinda Williams","album":"Essence","streaming":true,"disableUiControls":true,"duration":279,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:55:24 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::syncState stateService stop Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: info: No code Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 30 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand clear took 19 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 8 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 7 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 22ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 22ms Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:55:24 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:55:24 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 24ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand consume 1 took 23 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: info: [1772117724482] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/beyond-flacm Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/beyond-flacm" Feb 26 15:55:24 volumio volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 55ms Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand add "http://stream.radioparadise.com/beyond-flacm" took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand play took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=5 Feb 26 15:55:24 volumio volumio[1136]: info: [1772117724492] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=5 Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 5 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 11ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:24 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:24 volumio volumio[1136]: info: Feb 26 15:55:24 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 2ms Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:24 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:24 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:24 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:24 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:55:24 volumio volumio[1136]: info: ------------------------------ 6ms Feb 26 15:55:25 volumio volumio[1136]: info: [1772117725373] [RadioParadise] received new metadata: {"time":452,"artist":"Herbie Hancock","title":"Hornets","album":"Sextant","year":"1973","cover":"https://img.radioparadise.com/covers/l/20328.jpg","cover_med":"https://img.radioparadise.com/covers/m/20328.jpg","cover_small":"https://img.radioparadise.com/covers/s/20328.jpg"} Feb 26 15:55:25 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:25 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:25 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:25 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/20328.jpg","uri":"http://stream.radioparadise.com/beyond-flacm","name":"Hornets","title":"Hornets","artist":"Herbie Hancock","album":"Sextant","streaming":true,"disableUiControls":true,"duration":457,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:55:25 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:25 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:25 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:55:25 volumio volumio[1136]: info: [1772117725374] [RadioParadise] setting new timer with duration of 457000 seconds. Feb 26 15:55:25 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:25 volumio volumio[1136]: info: CorePlayQueue::getTrack 1 Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:27 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:27 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:27 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:27 volumio volumio[1136]: info: Feb 26 15:55:27 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:27 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:27 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:27 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:27 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:27 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:27 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:27 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:27 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:27 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:27 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:27 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:27 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:55:27 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:30 volumio volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 15:55:30 volumio volumio[1136]: info: CoreCommandRouter::Close All Modals sent Feb 26 15:55:54 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::stPlaybackTimer Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::serviceStop Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::serviceStop Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::stop Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Feb 26 15:55:54 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:55:54 volumio volumio[1136]: info: Adding Item to queue: webrp/12 Feb 26 15:55:54 volumio volumio[1136]: info: Exploding uri webrp/12 in service radio_paradise Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::play index 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::play index undefined Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand stop took 20 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand stop took 5 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/20328.jpg","uri":"http://stream.radioparadise.com/beyond-flacm","name":"Hornets","title":"Hornets","artist":"Herbie Hancock","album":"Sextant","streaming":true,"disableUiControls":true,"duration":457,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService stop Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: No code Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 27 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand clear took 19 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 10 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/serenity" Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 23ms Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 22ms Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 21ms Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand add "http://stream.radioparadise.com/serenity" took 19 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 6ms Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Feb 26 15:55:54 volumio volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 51ms Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:54 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand play took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: Feb 26 15:55:54 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 5 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:55:54 volumio volumio[1136]: info: ------------------------------ 11ms Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:55:54 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:54 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:54 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:54 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:54 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"serenity","artist":null,"album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:54 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:54 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:54 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 102ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 102ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 101ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 100ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 99ms Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:55 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:55 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:55:55 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:55:55 volumio volumio[1136]: info: Feb 26 15:55:55 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 21ms Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand status took 20 milliseconds Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 11ms Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand status took 11 milliseconds Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 11ms Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand status took 10 milliseconds Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:55:55 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:55 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:55 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:55 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:55 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:55 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:55 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:55 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:55 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:55 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:55:55 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:55:55 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 26 15:55:55 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:55:55 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:55:55 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 54ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 45ms Feb 26 15:55:55 volumio volumio[1136]: info: ------------------------------ 44ms Feb 26 15:56:03 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::ClearQueue Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::stPlaybackTimer Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::serviceStop Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::serviceStop Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::stop Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::clearPlayQueue Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::addQueueItems Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::addQueueItems Feb 26 15:56:03 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:56:03 volumio volumio[1136]: info: Adding Item to queue: webrp/9 Feb 26 15:56:03 volumio volumio[1136]: info: Exploding uri webrp/9 in service radio_paradise Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushQueue Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::saveQueue Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::updateTrackBlock Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrackBlock Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPlay Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::play index 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::stop Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::play index undefined Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::startPlaybackTimer Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: [RadioParadise] Matched station: Radio Paradise Radio 2050 (FLAC), chan=9 Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand stop took 26 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand stop took 7 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/20328.jpg","uri":"http://stream.radioparadise.com/beyond-flacm","name":"Hornets","title":"Hornets","artist":"Herbie Hancock","album":"Sextant","streaming":true,"disableUiControls":true,"duration":457,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:56:03 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::syncState stateService stop Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: info: No code Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand status took 29 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand clear took 20 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand status took 10 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand status took 9 milliseconds Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:03 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:03 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:03 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:03 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 27ms Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 26ms Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:56:03 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:56:03 volumio volumio[1136]: error: updateQueue error: null Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 27ms Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand consume 1 took 26 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:03 volumio volumio[1136]: info: [1772117763958] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/radio2050-flacm Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" Feb 26 15:56:03 volumio volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 57ms Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" took 2 milliseconds Feb 26 15:56:03 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: info: Feb 26 15:56:03 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:03 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 11ms Feb 26 15:56:03 volumio volumio[1136]: info: sendMpdCommand play took 6 milliseconds Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 5ms Feb 26 15:56:03 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:56:03 volumio volumio[1136]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=9 Feb 26 15:56:03 volumio volumio[1136]: info: [1772117763972] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=9 Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 4 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:04 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:04 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:04 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:04 volumio volumio[1136]: info: Feb 26 15:56:04 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 4ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand status took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:56:04 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:04 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:04 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:04 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 8ms Feb 26 15:56:04 volumio volumio[1136]: info: ------------------------------ 7ms Feb 26 15:56:06 volumio volumio[1136]: info: [1772117766276] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":"The Universe Smiles Upon You ii","year":null,"cover":"https://img.radioparadise.com/covers/l/0.jpg","cover_med":"https://img.radioparadise.com/covers/m/0.jpg","cover_small":"https://img.radioparadise.com/covers/s/0.jpg"} Feb 26 15:56:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:06 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:06 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/0.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":"The Universe Smiles Upon You ii","streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:56:06 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:56:06 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:56:06 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Feb 26 15:56:06 volumio volumio[1136]: info: [1772117766277] [RadioParadise] setting new timer with duration of 10000 seconds. Feb 26 15:56:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:06 volumio volumio[1136]: info: CorePlayQueue::getTrack 1 Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:07 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:07 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces system playlist update Feb 26 15:56:07 volumio volumio[1136]: info: Ignoring MPD Status Update Feb 26 15:56:07 volumio volumio[1136]: info: Feb 26 15:56:07 volumio volumio[1136]: ---------------------------- MPD announces state update: player Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::getState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 2ms Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 3ms Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand status took 3 milliseconds Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseState Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:07 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:07 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:07 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 15:56:07 volumio volumio[1136]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:07 volumio volumio[1136]: verbose: ControllerMpd::parseTrackInfo Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:07 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:07 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:07 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:07 volumio volumio[1136]: info: ControllerMpd::pushState Feb 26 15:56:07 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:07 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:07 volumio volumio[1136]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 10ms Feb 26 15:56:07 volumio volumio[1136]: info: ------------------------------ 9ms Feb 26 15:56:11 volumio volumio[1136]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 15:56:11 volumio volumio[1136]: info: CoreCommandRouter::Close All Modals sent Feb 26 15:56:16 volumio volumio[1136]: info: [1772117776277] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=9 Feb 26 15:56:17 volumio volumio[1136]: info: [1772117777014] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":"The Universe Smiles Upon You ii","year":null,"cover":"https://img.radioparadise.com/covers/l/0.jpg","cover_med":"https://img.radioparadise.com/covers/m/0.jpg","cover_small":"https://img.radioparadise.com/covers/s/0.jpg"} Feb 26 15:56:17 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:17 volumio volumio[1136]: info: CoreCommandRouter::servicePushState Feb 26 15:56:17 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:17 volumio volumio[1136]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/0.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":"The Universe Smiles Upon You ii","streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 15:56:17 volumio volumio[1136]: verbose: CURRENT POSITION 0 Feb 26 15:56:17 volumio volumio[1136]: info: CoreStateMachine::syncState stateService play Feb 26 15:56:17 volumio volumio[1136]: info: CoreStateMachine::syncState currentStatus play Feb 26 15:56:17 volumio volumio[1136]: info: Received an update from plugin. extracting info from payload Feb 26 15:56:17 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:17 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:17 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 15:56:17 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:17 volumio volumio[1136]: info: CoreStateMachine::pushState Feb 26 15:56:17 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 26 15:56:17 volumio volumio[1136]: info: CoreCommandRouter::volumioPushState Feb 26 15:56:17 volumio volumio[1136]: info: [1772117777026] [RadioParadise] setting new timer with duration of 10000 seconds. Feb 26 15:56:20 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 26 15:56:20 volumio volumio[1136]: info: Preload queue cleared Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2F Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2E Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2C Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2B Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2A Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2D Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1D Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$17 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$13 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$11 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$D Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$5 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$2 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$20 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1F Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1E Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1C Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1B Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1A Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$19 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$18 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$16 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$15 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$14 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$12 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$7 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$0 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: http://stream.radioparadise.com/global-flacm Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/02 - Philippe Elan - Tu es la.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/01 - Plas Johnson - Pink Panther Theme.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/15 - The Replacement Wheel.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/08 - Panta Rhei.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/07 - Kontur.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/05 - Tranquil Endeavour.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/04 - Weedless Hook.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/03 - Cromo 7.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/01 - Eterna 2.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Pop Ambient 2024 (2023) [FLAC 24-48]/02 - Octarine.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/folder/http://192.168.178.25:8200/ctl/ContentDir@1$14$78$13 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$78$14$1 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$5 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$3 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio The Diamond Revolution (2009) [FLAC] {Usher Audio Demonstration CD}/04 - Jheena Lodwick - You Raise My Up .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/09 - Eva - Dream About You.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/07 - Eleonora Holiday - The Devil May Care.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/04 - Nat King Cole - Sunny Get Blue.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/03 - Max and Esmay Luck - Hold On.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$1 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/06 - Harry Sacksioni - Hick Ups.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/08 - Blues Company - Homeward Bound.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/13 - Coleman Hawkins & Ben Webster - La Rosita.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/14 - George Carlo - Live Samba.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/16 - Ron and Marco - English Folksong.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/05 - Wies Ingwersen - Wailin Wall.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$10 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$F Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$E Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$C Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$B Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$A Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$9 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$8 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: https://stream.radioparadise.com/flacm Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/10 - Ricky Peterson - Let Me Down Easy.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/03. Caroline Henderson - Be Here Now.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/Audiophile Reference II - Popular Music (2003)[FLAC]/Various - Audiophile Reference II Popular Music.cue@0 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@0 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/14. Pernille Rosendahl - Feet On The Ground - Rework.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/17. Zar Paulo - Hills.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/16. Rhino - Summer In The City.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/09. Wangel - Time To Eat.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/08. GinmanBlachmanDahl - Eight Bars On The Way Home.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/07. Jacob Dinesen - Dancing Devil.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/01. Hush - Up To The Mountain.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/06. Oh Land - Desert Island.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/05. Operazione - Il Core Vi Dono (from Cosi Fan Tutte).flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/04. Go Go Berlin - Plastic Sky.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/The DALI CD Vol. 5 (2018)/02. Erann DD - Sugar Man.flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@5 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@4 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@3 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@2 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@1 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/03 - Gary Karr - O Holy Night .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/02 - Jim Keltner - Improvisation .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/06 - Bluevolution (B.B de Voeux) - Sound of silent .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/05 - Jacintha Here's to Ben - The Look of Love .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/04 - TIMBRE - Moonlight .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/09 - Jheena Lodwick - Too Young .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/11 - Wei Li - Mood & Dream of an Opera .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/12 - Marie Nakamoto - Georgia on my Mind .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/08 - Jheena Lodwick - Emerald City .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 3 (2006) [FLAC] {Usher Audio Demonstation CD}/02 - Stacey Kent & Jim Tomlinson - So Nice .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 3 (2006) [FLAC] {Usher Audio Demonstation CD}/01 - Xue Li - Oblivious Time .flac Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/Audiophile Reference II - Popular Music (2003)[FLAC]/Various - Audiophile Reference II Popular Music.cue@14 Feb 26 15:56:20 volumio volumio[1136]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/Audiophile Reference II - Popular Music (2003)[FLAC]/Various - Audiophile Reference II Popular Music.cue@13 Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2F in service upnp_browser Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2E in service upnp_browser Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2C in service upnp_browser Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2B in service upnp_browser Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2A in service upnp_browser Feb 26 15:56:20 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2D in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1D in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$17 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$13 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$11 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$D in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$5 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$2 in service upnp_browser Feb 26 15:56:21 volumio volumio[1136]: info: Exploding uri https://stream.radioparadise.com/mellow-flacm in service radio_paradise Feb 26 15:56:21 volumio volumio[1136]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/mellow-flacm" Feb 26 15:56:21 volumio volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 15:56:21 volumio volumio[1136]: TypeError: Cannot read properties of undefined (reading 'then') Feb 26 15:56:21 volumio volumio[1136]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Feb 26 15:56:21 volumio volumio[1136]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 26 15:56:21 volumio volumio[1136]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 26 15:56:21 volumio volumio[1136]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Feb 26 15:56:21 volumio volumio[1136]: at listOnTimeout (node:internal/timers:573:17) Feb 26 15:56:21 volumio volumio[1136]: at process.processTimers (node:internal/timers:514:7) Feb 26 15:56:21 volumio volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 15:56:21 volumio sudo[2848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 15:55' Feb 26 15:56:21 volumio sudo[2848]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"