-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-08-30 22:50:15 CEST. -- Aug 30 22:49:00 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Aug 30 22:49:00 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:06 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::ClearQueue Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::stop Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::stPlaybackTimer Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrackBlock Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::serviceStop Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::serviceStop Aug 30 22:49:06 volumio volumio[963]: info: [1756586946187] ControllerWebradio::stop Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::clearPlayQueue Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::saveQueue Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::addQueueItems Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::addQueueItems Aug 30 22:49:06 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:06 volumio volumio[963]: info: Adding Item to queue: webrp/1 Aug 30 22:49:06 volumio volumio[963]: info: Exploding uri webrp/1 in service radio_paradise Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::saveQueue Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::updateTrackBlock Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrackBlock Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPlay Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::play index 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::stop Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::play index undefined Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand stop took 29 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand stop took 4 milliseconds Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 11 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand clear took 8 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 5 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 4 milliseconds Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 28ms Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 27ms Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: error: updateQueue error: null Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: options Aug 30 22:49:06 volumio volumio[963]: error: updateQueue error: null Aug 30 22:49:06 volumio volumio[963]: error: updateQueue error: null Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 32ms Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand consume 1 took 29 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 8ms Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 7ms Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:06 volumio volumio[963]: info: [1756586946264] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Aug 30 22:49:06 volumio volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 50ms Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: options Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces state update: options Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 14 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 13 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 10 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand status took 9 milliseconds Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:49:06 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:06 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::pushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::volumioPushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 41ms Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 40ms Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 39ms Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: info: Aug 30 22:49:06 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:06 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 45ms Aug 30 22:49:06 volumio volumio[963]: info: sendMpdCommand play took 36 milliseconds Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 13ms Aug 30 22:49:06 volumio volumio[963]: info: ------------------------------ 12ms Aug 30 22:49:06 volumio volumio[963]: info: [1756586946323] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Aug 30 22:49:06 volumio volumio[963]: info: [1756586946859] [RadioParadise] received new metadata: {"time":186,"artist":"Norah Jones","title":"In The Morning","album":"Feels Like Home","year":"2004","cover":"https://img.radioparadise.com/covers/l/B00018D44U.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00018D44U.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00018D44U.jpg"} Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00018D44U.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"In The Morning","title":"In The Morning","artist":"Norah Jones","album":"Feels Like Home","streaming":true,"disableUiControls":true,"duration":191,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 30 22:49:06 volumio volumio[963]: verbose: CURRENT POSITION 0 Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::syncState stateService play Aug 30 22:49:06 volumio volumio[963]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:49:06 volumio volumio[963]: info: [1756586946864] [RadioParadise] setting new timer with duration of 191000 seconds. Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:06 volumio volumio[963]: info: CorePlayQueue::getTrack 1 Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:07 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 21 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 20 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 8 milliseconds Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:07 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 22 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 20 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 23ms Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 21 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 18 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 17ms Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 13 milliseconds Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 60ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 57ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 56ms Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces system playlist update Aug 30 22:49:07 volumio volumio[963]: info: Ignoring MPD Status Update Aug 30 22:49:07 volumio volumio[963]: info: Aug 30 22:49:07 volumio volumio[963]: ---------------------------- MPD announces state update: player Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::getState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 57ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 55ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 54ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 44ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 40ms Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 16ms Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand status took 13 milliseconds Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseState Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:49:07 volumio volumio[963]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:49:07 volumio volumio[963]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:49:07 volumio volumio[963]: info: ControllerMpd::pushState Aug 30 22:49:07 volumio volumio[963]: info: CoreCommandRouter::servicePushState Aug 30 22:49:07 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:07 volumio volumio[963]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Aug 30 22:49:07 volumio volumio[963]: info: ------------------------------ 19ms Aug 30 22:49:17 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:49:17 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:49:17 volumio volumio[963]: info: Discovery: Getting this device information Aug 30 22:49:17 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Aug 30 22:49:17 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:17 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:49:27 volumio volumio[963]: info: Adding https://stream.radioparadise.com/mellow-flac to favourites using generic method Aug 30 22:49:27 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , explodeUri Aug 30 22:49:27 volumio volumio[963]: error: Could not add to playlist: https://stream.radioparadise.com/mellow-flac Aug 30 22:49:44 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:49:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:49:47 volumio volumio[963]: info: Discovery: Getting this device information Aug 30 22:49:47 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Aug 30 22:49:47 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Aug 30 22:49:47 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:49:50 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 22:49:50 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:52 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 22:49:52 volumio volumio[963]: info: Preload queue cleared Aug 30 22:49:57 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 22:49:57 volumio volumio[963]: info: Getting webradio selection Aug 30 22:49:57 volumio volumio[963]: info: Preload queue cleared Aug 30 22:50:05 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 22:50:05 volumio volumio[963]: info: Preload queue cleared Aug 30 22:50:07 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 22:50:10 volumio volumio[963]: info: Preload queue cleared Aug 30 22:50:12 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 22:50:12 volumio volumio[963]: info: Preload queue cleared Aug 30 22:50:14 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 22:50:14 volumio volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:50:14 volumio volumio[963]: TypeError: Cannot read property 'length' of undefined Aug 30 22:50:14 volumio volumio[963]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 30 22:50:14 volumio volumio[963]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 30 22:50:14 volumio volumio[963]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 30 22:50:14 volumio volumio[963]: at Parser.emit (events.js:315:20) Aug 30 22:50:14 volumio volumio[963]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 30 22:50:14 volumio volumio[963]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 30 22:50:14 volumio volumio[963]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 30 22:50:14 volumio volumio[963]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 30 22:50:14 volumio volumio[963]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 30 22:50:14 volumio volumio[963]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 30 22:50:14 volumio volumio[963]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 30 22:50:14 volumio volumio[963]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 30 22:50:14 volumio volumio[963]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 30 22:50:14 volumio volumio[963]: at IncomingMessage.emit (events.js:327:22) Aug 30 22:50:14 volumio volumio[963]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 30 22:50:14 volumio volumio[963]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 22:50:14 volumio volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:50:15 volumio sudo[21630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 22:49 Aug 30 22:50:15 volumio sudo[21630]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"