Sep 05 11:52:13 volumio4pi5 go-librespot[32421]: time="2025-09-05T11:52:13+02:00" level=trace msg="sent dealer ping" Sep 05 11:52:13 volumio4pi5 go-librespot[32421]: time="2025-09-05T11:52:13+02:00" level=trace msg="received dealer pong" Sep 05 11:52:19 volumio4pi5 systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 05 11:52:43 volumio4pi5 go-librespot[32421]: time="2025-09-05T11:52:43+02:00" level=trace msg="sent dealer ping" Sep 05 11:52:43 volumio4pi5 go-librespot[32421]: time="2025-09-05T11:52:43+02:00" level=trace msg="received dealer pong" Sep 05 11:52:52 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Sep 05 11:52:52 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::ClearQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::serviceStop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::serviceStop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Spotify Stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: SPOTIFY STOP Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: {"status":"play","title":"Pyrex Vision","artist":"Samy Deluxe, Conductor Williams, Afrob","album":"SAMY x CONDUCTOR","albumart":"https://i.scdn.co/image/ab67616d00001e0208822275402ec2875eb89c2b","uri":"spotify:track:7dMEp0Jnw2eK2BLKUTwwHy","trackType":"spotify","codec":"ogg","seek":1000,"duration":187,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":80,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sending Spotify command to local API: /player/pause Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::clearPlayQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::addQueueItems Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::addQueueItems Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Exploding uri webrp/0 in service radio_paradise Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPlay Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 13 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::serviceStop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 13 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 10 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index undefined Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::startPlaybackTimer Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand clear took 5 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:53 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:53 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: options Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 7ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand consume 1 took 4 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: [1757065973445] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: options Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: options Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 3 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 3 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 10ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 10ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand play took 8 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 0ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: [1757065973459] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:53 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Sep 05 11:52:53 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 9 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 10ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 10ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 3 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 2 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 12ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 05 11:52:53 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:53 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: [1757065974049] [RadioParadise] received new metadata: {"time":147,"artist":"King Crimson","title":"Matte Kudasai","album":"Discipline","year":"1981","cover":"https://img.radioparadise.com/covers/l/B000197IDW.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000197IDW.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000197IDW.jpg"} Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:54 volumio4pi5 volumio[1387]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B000197IDW.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Matte Kudasai","title":"Matte Kudasai","artist":"King Crimson","album":"Discipline","streaming":true,"disableUiControls":true,"duration":152,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 05 11:52:54 volumio4pi5 volumio[1387]: verbose: CURRENT POSITION 0 Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState stateService play Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: [1757065974050] [RadioParadise] setting new timer with duration of 152000 seconds. Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:54 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::ClearQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::serviceStop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::serviceStop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::clearPlayQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::addQueueItems Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::addQueueItems Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Exploding uri webrp/1 in service radio_paradise Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPlay Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index undefined Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::startPlaybackTimer Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: [RadioParadise] Matched station: Radio Paradise Mellow Mix (FLAC), chan=1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 8 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 2 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B000197IDW.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Matte Kudasai","title":"Matte Kudasai","artist":"King Crimson","album":"Discipline","streaming":true,"disableUiControls":true,"duration":152,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: CURRENT POSITION 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState stateService stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: No code Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 4 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand clear took 2 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 0 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 5ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 5ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:52:58 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:58 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:58 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 8ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand consume 1 took 8 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: [1757065978618] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flacm Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" Sep 05 11:52:58 volumio4pi5 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 15ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand play took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 0ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 0ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: [1757065978620] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Sep 05 11:52:58 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 6 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 6 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 6 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 8ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 2 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 2 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 9ms Sep 05 11:52:58 volumio4pi5 volumio[1387]: info: ------------------------------ 8ms Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: [1757065979191] [RadioParadise] received new metadata: {"time":38,"artist":"Ben Folds","title":"Annie Waits","album":"Rockin' The Suburbs","year":"2001","cover":"https://img.radioparadise.com/covers/l/B00005NZKK.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00005NZKK.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00005NZKK.jpg"} Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:52:59 volumio4pi5 volumio[1387]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00005NZKK.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Annie Waits","title":"Annie Waits","artist":"Ben Folds","album":"Rockin' The Suburbs","streaming":true,"disableUiControls":true,"duration":43,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 05 11:52:59 volumio4pi5 volumio[1387]: verbose: CURRENT POSITION 0 Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState stateService play Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: [1757065979192] [RadioParadise] setting new timer with duration of 43000 seconds. Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 11:52:59 volumio4pi5 volumio[1387]: info: CoreCommandRouter::Close All Modals sent Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Preloading song: webrp/2 Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Preloading song: webrp/3 Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Preloading song: webrp/13 Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Exploding uri webrp/2 in service radio_paradise Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Exploding uri webrp/3 in service radio_paradise Sep 05 11:53:00 volumio4pi5 volumio[1387]: info: Exploding uri webrp/13 in service radio_paradise Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::ClearQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::serviceStop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::serviceStop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::clearPlayQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::addQueueItems Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::addQueueItems Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Using cached record of: webrp/0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPlay Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::addQueueItems Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::addQueueItems Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/1 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Using cached record of: webrp/1 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/2 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Using cached record of: webrp/2 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/3 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Using cached record of: webrp/3 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Adding Item to queue: webrp/13 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Using cached record of: webrp/13 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::saveQueue Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::play index undefined Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::updateTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrackBlock Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::startPlaybackTimer Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 14 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand stop took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00005NZKK.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Annie Waits","title":"Annie Waits","artist":"Ben Folds","album":"Rockin' The Suburbs","streaming":true,"disableUiControls":true,"duration":43,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: CURRENT POSITION 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState stateService stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: No code Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 16 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand clear took 7 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 5 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 4 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::volumioPushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 8ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 8ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:53:01 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:53:01 volumio4pi5 volumio[1387]: error: updateQueue error: null Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand consume 1 took 4 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [1757065981501] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Sep 05 11:53:01 volumio4pi5 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 21ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 6 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces system playlist update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Ignoring MPD Status Update Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand play took 1 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 1ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [1757065981509] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [1757065981691] [RadioParadise] received new metadata: {"time":139,"artist":"King Crimson","title":"Matte Kudasai","album":"Discipline","year":"1981","cover":"https://img.radioparadise.com/covers/l/B000197IDW.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000197IDW.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000197IDW.jpg"} Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B000197IDW.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Matte Kudasai","title":"Matte Kudasai","artist":"King Crimson","album":"Discipline","streaming":true,"disableUiControls":true,"duration":144,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: CURRENT POSITION 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState stateService play Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: [1757065981691] [RadioParadise] setting new timer with duration of 144000 seconds. Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 1 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Prefetching next song Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Sep 05 11:53:01 volumio4pi5 volumio[1387]: ---------------------------- MPD announces state update: player Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::getState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 8 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 7 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand clearerror took 7 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 7 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseState Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 12ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ControllerMpd::pushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CoreCommandRouter::servicePushState Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: CorePlayQueue::getTrack 0 Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 11ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:53:01 volumio4pi5 volumio[1387]: info: ------------------------------ 4ms Sep 05 11:53:04 volumio4pi5 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 11:53:04 volumio4pi5 volumio[1387]: info: CoreCommandRouter::Close All Modals sent Sep 05 11:53:05 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 05 11:53:05 volumio4pi5 volumio[1387]: info: CURURI: playlists Sep 05 11:53:05 volumio4pi5 volumio[1387]: info: Listing playlists Sep 05 11:53:05 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: CURURI: playlists/A Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preload queue cleared Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: https://frontend.streamonkey.net/charivari-live/stream/mp3?aggregator=TuneIn Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: https://streams.egofm.de/egoFM-hq Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: https://stream.radioparadise.com/flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: http://stream.radioparadise.com/global-flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Preloading song: http://stream.radioparadise.com/radio2050-flacm Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 11:53:06 volumio4pi5 volumio[1387]: info: Exploding uri https://stream.radioparadise.com/flacm in service radio_paradise Sep 05 11:53:06 volumio4pi5 volumio[1387]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/flacm" Sep 05 11:53:06 volumio4pi5 volumio[1387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 11:53:06 volumio4pi5 volumio[1387]: TypeError: Cannot read properties of undefined (reading 'then') Sep 05 11:53:06 volumio4pi5 volumio[1387]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Sep 05 11:53:06 volumio4pi5 volumio[1387]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Sep 05 11:53:06 volumio4pi5 volumio[1387]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Sep 05 11:53:06 volumio4pi5 volumio[1387]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Sep 05 11:53:06 volumio4pi5 volumio[1387]: at listOnTimeout (node:internal/timers:573:17) Sep 05 11:53:06 volumio4pi5 volumio[1387]: at process.processTimers (node:internal/timers:514:7) Sep 05 11:53:06 volumio4pi5 volumio[1387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 11:53:06 volumio4pi5 sudo[12362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-05 11:52' Sep 05 11:53:06 volumio4pi5 sudo[12362]: 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="477d19cd2b4ebf4b4c802be78e00bbb657e83aea" 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 Aug 2 17:34:53 UTC 2025" VOLUMIO_VERSION="4.021" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6199706cbb86ad1289fc7190b8a00601"