Dec 16 13:12:08 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:12:19 volumio volumio[1231]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Dec 16 13:12:19 volumio volumio[1231]: info: CoreStateMachine::getQueue Dec 16 13:12:19 volumio volumio[1231]: info: CorePlayQueue::getQueue Dec 16 13:12:19 volumio volumio[1231]: info: Saving local item radio Dec 16 13:12:34 volumio volumio[1231]: info: CoreCommandRouter::volumioMoveQueue Dec 16 13:12:34 volumio volumio[1231]: info: CoreStateMachine::moveQueueItem 24 1 Dec 16 13:12:34 volumio volumio[1231]: info: CorePlayQueue::moveQueueItem 24 --> 1 Dec 16 13:12:34 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::play index 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:12:37 volumio volumio[1231]: info: [1765887157088] ControllerWebradio::stop Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand stop took 17 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: [RadioParadise] Matched station: Radio Paradise Rock Mix (FLAC), chan=2 Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 2 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand stop took 1 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand clear took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 2 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 24ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 20ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 19ms Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:37 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:37 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 21ms Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand consume 1 took 19 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 3ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 2ms Dec 16 13:12:37 volumio volumio[1231]: info: [1765887157135] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flacm Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand play took 5 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 3ms Dec 16 13:12:37 volumio volumio[1231]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 13:12:37 volumio volumio[1231]: info: [1765887157146] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 13:12:37 volumio volumio[1231]: info: [1765887157712] [RadioParadise] received new metadata: {"time":25,"artist":"The Cars","title":"Blue Tip","album":"Move Like This","year":"2011","cover":"https://img.radioparadise.com/covers/l/10302.jpg","cover_med":"https://img.radioparadise.com/covers/m/10302.jpg","cover_small":"https://img.radioparadise.com/covers/s/10302.jpg"} Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:12:37 volumio volumio[1231]: verbose: CURRENT POSITION 1 Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:37 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:12:37 volumio volumio[1231]: info: [1765887157714] [RadioParadise] setting new timer with duration of 30000 seconds. Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 2 Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 21 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 23 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 22 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 28ms Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 8 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 7 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 40ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 39ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 19ms Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 17ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 16ms Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:37 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:37 volumio volumio[1231]: info: Dec 16 13:12:37 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 7 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:37 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:37 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:37 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 14ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 10ms Dec 16 13:12:37 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:40 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:40 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:40 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:40 volumio volumio[1231]: info: Dec 16 13:12:40 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 10ms Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand status took 9 milliseconds Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand status took 5 milliseconds Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand status took 5 milliseconds Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:40 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:40 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:40 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 18ms Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 13:12:40 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:40 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:40 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:40 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:40 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:40 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:40 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:40 volumio volumio[1231]: info: CorePlayQueue::getTrack 1 Dec 16 13:12:40 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 17ms Dec 16 13:12:40 volumio volumio[1231]: info: ------------------------------ 14ms Dec 16 13:12:41 volumio volumio[1231]: info: CoreCommandRouter::volumioMoveQueue Dec 16 13:12:41 volumio volumio[1231]: info: CoreStateMachine::moveQueueItem 1 4 Dec 16 13:12:41 volumio volumio[1231]: info: CorePlayQueue::moveQueueItem 1 --> 4 Dec 16 13:12:41 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:12:43 volumio volumio[1231]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 13:12:43 volumio volumio[1231]: info: CoreCommandRouter::Close All Modals sent Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::play index 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand stop took 20 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":30,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:12:45 volumio volumio[1231]: verbose: CURRENT POSITION 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: No code Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: [RadioParadise] Matched station: Radio Paradise Rock Mix (FLAC), chan=2 Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand status took 12 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand stop took 1 milliseconds Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand clear took 13 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand status took 13 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand status took 12 milliseconds Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:45 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:45 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:45 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:45 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 36ms Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 24ms Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 23ms Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:45 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:45 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 17ms Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand consume 1 took 15 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 3ms Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 2ms Dec 16 13:12:45 volumio volumio[1231]: info: [1765887165563] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flacm Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" took 6 milliseconds Dec 16 13:12:45 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: info: Dec 16 13:12:45 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:45 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:12:45 volumio volumio[1231]: info: sendMpdCommand play took 7 milliseconds Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:12:45 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:12:45 volumio volumio[1231]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 13:12:45 volumio volumio[1231]: info: [1765887165579] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Dec 16 13:12:46 volumio volumio[1231]: info: [1765887166157] [RadioParadise] received new metadata: {"time":15,"artist":"The Cars","title":"Blue Tip","album":"Move Like This","year":"2011","cover":"https://img.radioparadise.com/covers/l/10302.jpg","cover_med":"https://img.radioparadise.com/covers/m/10302.jpg","cover_small":"https://img.radioparadise.com/covers/s/10302.jpg"} Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:12:46 volumio volumio[1231]: verbose: CURRENT POSITION 4 Dec 16 13:12:46 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:46 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:12:46 volumio volumio[1231]: info: [1765887166158] [RadioParadise] setting new timer with duration of 20000 seconds. Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 20 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 21 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 20 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 27ms Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 7 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 39ms Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 37ms Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 21ms Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 16ms Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 15ms Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:46 volumio volumio[1231]: info: Dec 16 13:12:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 5 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 5ms Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 5ms Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand status took 3 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 11ms Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 13:12:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 10ms Dec 16 13:12:46 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:12:48 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:12:48 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:48 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:48 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:48 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:48 volumio volumio[1231]: info: Dec 16 13:12:48 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 5ms Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand status took 5 milliseconds Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:48 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:48 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:48 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:48 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:48 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:48 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 15ms Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 13ms Dec 16 13:12:48 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 13:12:48 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:48 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:48 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:48 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:48 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:48 volumio volumio[1231]: info: ------------------------------ 12ms Dec 16 13:12:51 volumio volumio[1231]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 13:12:51 volumio volumio[1231]: info: CoreCommandRouter::Close All Modals sent Dec 16 13:12:52 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::ClearQueue Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 4 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::clearPlayQueue Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::addQueueItems Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::addQueueItems Dec 16 13:12:52 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:12:52 volumio volumio[1231]: info: Adding Item to queue: webrp/8 Dec 16 13:12:52 volumio volumio[1231]: info: Exploding uri webrp/8 in service radio_paradise Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::play index 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand stop took 24 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: No code Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand stop took 23 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 14 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand clear took 13 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 13 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 12 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 22ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 21ms Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 14ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 13 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 3ms Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:12:52 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 33ms Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 14ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand play took 11 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 25 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 28 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 27 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 10 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 68ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 72ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 73ms Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 49 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 48ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 46 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 13ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 14 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 12ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 10 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 91ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 84ms Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:12:52 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:12:52 volumio volumio[1231]: info: Dec 16 13:12:52 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 55ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 53 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 24ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 24 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 23ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand status took 22 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":549,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 178ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 177ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 139ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 136ms Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 76 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 76 milliseconds Dec 16 13:12:52 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 76 milliseconds Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:12:52 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:12:52 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:12:52 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:12:52 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:12:52 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 175ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 142ms Dec 16 13:12:52 volumio volumio[1231]: info: ------------------------------ 142ms Dec 16 13:12:54 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:12:59 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:13:06 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::ClearQueue Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::clearPlayQueue Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::addQueueItems Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::addQueueItems Dec 16 13:13:06 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:06 volumio volumio[1231]: info: Adding Item to queue: webrp/4 Dec 16 13:13:06 volumio volumio[1231]: info: Exploding uri webrp/4 in service radio_paradise Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::play index 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand stop took 18 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand stop took 4 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: No code Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 24 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand clear took 17 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 9 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 8 milliseconds Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 24ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 22ms Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 21ms Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" took 16 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:13:06 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 48ms Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 15ms Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand play took 12 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 10ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 19 milliseconds Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 24 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 23 milliseconds Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 11 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 8 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:06 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":779,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:06 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 48ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 53ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 54ms Dec 16 13:13:06 volumio volumio[1231]: info: Dec 16 13:13:06 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:06 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 32ms Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand status took 30 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 4ms Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:06 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:06 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 62ms Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 61ms Dec 16 13:13:06 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 16 13:13:06 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:06 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:06 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:06 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 16 13:13:06 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:06 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:06 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:06 volumio volumio[1231]: info: ------------------------------ 88ms Dec 16 13:13:20 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:13:29 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::ClearQueue Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::clearPlayQueue Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::addQueueItems Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::addQueueItems Dec 16 13:13:29 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:29 volumio volumio[1231]: info: Adding Item to queue: webrp/8 Dec 16 13:13:29 volumio volumio[1231]: info: Using cached record of: webrp/8 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::play index 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand stop took 24 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand stop took 8 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:13:29 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: info: No code Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand status took 32 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand clear took 18 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand status took 15 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand status took 13 milliseconds Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:29 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:29 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:29 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 36ms Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 35ms Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 37ms Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 29 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 12ms Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 10ms Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:29 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:13:29 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 78ms Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: info: Dec 16 13:13:29 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:29 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 17ms Dec 16 13:13:29 volumio volumio[1231]: info: sendMpdCommand play took 11 milliseconds Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:13:29 volumio volumio[1231]: info: ------------------------------ 6ms Dec 16 13:13:29 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 20 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 18 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 18 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 12 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 8 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 67ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 72ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 71ms Dec 16 13:13:30 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::ClearQueue Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::clearPlayQueue Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::addQueueItems Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::addQueueItems Dec 16 13:13:30 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:30 volumio volumio[1231]: info: Adding Item to queue: webrp/8 Dec 16 13:13:30 volumio volumio[1231]: info: Using cached record of: webrp/8 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::play index 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 94 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 89 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand stop took 37 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand stop took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: No code Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 147ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 139ms Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:30 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 70ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 66 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 64 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand clear took 40 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 29ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 28 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 27ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 27 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 182ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 48ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 46ms Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 36ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 25 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 7 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 7ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 5 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:13:30 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 120ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 119ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 27ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 26ms Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 30ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand play took 24 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 7ms Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 24 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 27 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 26 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 44ms Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 20 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 74ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 73ms Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:30 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:30 volumio volumio[1231]: info: Dec 16 13:13:30 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 38 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 37ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 36 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 11ms Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 88ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 75ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 65 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 64ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand status took 63 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 132ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 131ms Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 16 13:13:30 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:30 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:30 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:30 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:30 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:30 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 126ms Dec 16 13:13:30 volumio volumio[1231]: info: ------------------------------ 123ms Dec 16 13:13:34 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:13:45 volumio volumio[1231]: info: CoreCommandRouter::volumioGetState Dec 16 13:13:46 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::ClearQueue Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::stPlaybackTimer Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::serviceStop Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::serviceStop Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::stop Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::clearPlayQueue Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::addQueueItems Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::addQueueItems Dec 16 13:13:46 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:46 volumio volumio[1231]: info: Adding Item to queue: webrp/8 Dec 16 13:13:46 volumio volumio[1231]: info: Using cached record of: webrp/8 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushQueue Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::saveQueue Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::updateTrackBlock Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrackBlock Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPlay Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::play index 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::stop Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::play index undefined Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::startPlaybackTimer Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand stop took 19 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand stop took 6 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10302.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Blue Tip","title":"Blue Tip","artist":"The Cars","album":"Move Like This","streaming":true,"disableUiControls":true,"duration":20,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 13:13:46 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::syncState stateService stop Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: info: No code Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand status took 28 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand clear took 17 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand status took 11 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand status took 10 milliseconds Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:46 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:46 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:46 volumio volumio[1231]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 29ms Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 27ms Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: error: updateQueue error: null Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 27ms Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 24 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 9ms Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:46 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand play Dec 16 13:13:46 volumio volumio[1231]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 62ms Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 16ms Dec 16 13:13:46 volumio volumio[1231]: info: sendMpdCommand play took 8 milliseconds Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 7ms Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 5ms Dec 16 13:13:46 volumio volumio[1231]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 13:13:46 volumio volumio[1231]: info: Dec 16 13:13:46 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:46 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:46 volumio volumio[1231]: info: ------------------------------ 2ms Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 25 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 27 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 25 milliseconds Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 11 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 7 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus stop Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 59ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 62ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 62ms Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 40 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 40ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 37 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 13ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 11 milliseconds Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 98ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 91ms Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 66ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 65 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 63ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 62 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 15ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 13 milliseconds Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 153ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 151ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 123ms Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces system playlist update Dec 16 13:13:47 volumio volumio[1231]: info: Ignoring MPD Status Update Dec 16 13:13:47 volumio volumio[1231]: info: Dec 16 13:13:47 volumio volumio[1231]: ---------------------------- MPD announces state update: player Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::getState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 8ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand status took 6 milliseconds Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseState Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 162ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 160ms Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 110ms Dec 16 13:13:47 volumio volumio[1231]: info: sendMpdCommand playlistinfo took 55 milliseconds Dec 16 13:13:47 volumio volumio[1231]: verbose: ControllerMpd::parseTrackInfo Dec 16 13:13:47 volumio volumio[1231]: info: ControllerMpd::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::servicePushState Dec 16 13:13:47 volumio volumio[1231]: info: CorePlayQueue::getTrack 0 Dec 16 13:13:47 volumio volumio[1231]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radiohead - All I Need","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 16 13:13:47 volumio volumio[1231]: verbose: CURRENT POSITION 0 Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState stateService play Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::syncState currentStatus play Dec 16 13:13:47 volumio volumio[1231]: info: Received an update from plugin. extracting info from payload Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreStateMachine::pushState Dec 16 13:13:47 volumio volumio[1231]: info: CoreCommandRouter::volumioPushState Dec 16 13:13:47 volumio volumio[1231]: info: ------------------------------ 79ms Dec 16 13:13:49 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 13:13:49 volumio volumio[1231]: info: CURURI: playlists Dec 16 13:13:49 volumio volumio[1231]: info: Listing playlists Dec 16 13:13:49 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:51 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 13:13:51 volumio volumio[1231]: info: CURURI: playlists/Radio Dec 16 13:13:51 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:56 volumio volumio[1231]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 13:13:56 volumio volumio[1231]: info: CURURI: playlists/radio Dec 16 13:13:56 volumio volumio[1231]: info: Preload queue cleared Dec 16 13:13:56 volumio volumio[1231]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Dec 16 13:13:56 volumio volumio[1231]: info: Exploding uri https://stream.radioparadise.com/rock-flacm in service radio_paradise Dec 16 13:13:56 volumio volumio[1231]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/rock-flacm" Dec 16 13:13:56 volumio volumio[1231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 13:13:56 volumio volumio[1231]: TypeError: Cannot read properties of undefined (reading 'then') Dec 16 13:13:56 volumio volumio[1231]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Dec 16 13:13:56 volumio volumio[1231]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 16 13:13:56 volumio volumio[1231]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 16 13:13:56 volumio volumio[1231]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Dec 16 13:13:56 volumio volumio[1231]: at listOnTimeout (node:internal/timers:573:17) Dec 16 13:13:56 volumio volumio[1231]: at process.processTimers (node:internal/timers:514:7) Dec 16 13:13:56 volumio volumio[1231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 13:13:57 volumio sudo[14770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 13:12' Dec 16 13:13:57 volumio sudo[14770]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"