Apr 11 10:32:07 volumio volumio[1268]: info: Preload queue cleared Apr 11 10:32:07 volumio volumio[1268]: info: CoreCommandRouter::volumioAddQueueItems Apr 11 10:32:07 volumio volumio[1268]: info: CoreStateMachine::addQueueItems Apr 11 10:32:07 volumio volumio[1268]: info: CorePlayQueue::addQueueItems Apr 11 10:32:07 volumio volumio[1268]: info: Preload queue cleared Apr 11 10:32:07 volumio volumio[1268]: info: Adding Item to queue: webrp/0 Apr 11 10:32:07 volumio volumio[1268]: info: Exploding uri webrp/0 in service radio_paradise Apr 11 10:32:07 volumio volumio[1268]: info: CoreCommandRouter::volumioPushQueue Apr 11 10:32:07 volumio volumio[1268]: info: CorePlayQueue::saveQueue Apr 11 10:32:07 volumio volumio[1268]: info: CoreStateMachine::updateTrackBlock Apr 11 10:32:07 volumio volumio[1268]: info: CorePlayQueue::getTrackBlock Apr 11 10:32:07 volumio volumio[1268]: info: CoreCommandRouter::volumioPlay Apr 11 10:32:07 volumio volumio[1268]: info: CoreStateMachine::play index 25 Apr 11 10:32:07 volumio volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::stop Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::stPlaybackTimer Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::updateTrackBlock Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrackBlock Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 24 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::serviceStop Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 24 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::serviceStop Apr 11 10:32:08 volumio volumio[1268]: info: [1775871128003] ControllerWebradio::stop Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.005+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=0 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.006+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99593847" title="Flashback Alternatives" Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand stop took 69 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::play index undefined Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::startPlaybackTimer Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand stop took 2 milliseconds Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand clear took 7 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 6 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 26ms Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 20ms Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 19ms Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.095+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=10 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.096+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=10 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.096+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.096+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=10 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.097+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.097+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: error: updateQueue error: null Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: options Apr 11 10:32:08 volumio volumio[1268]: error: updateQueue error: null Apr 11 10:32:08 volumio volumio[1268]: error: updateQueue error: null Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 20ms Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand consume 1 took 18 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 4ms Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 2ms Apr 11 10:32:08 volumio volumio[1268]: info: [1775871128105] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: options Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces state update: options Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 8 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 7 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 6 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand play Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.120+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=30 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.120+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 20ms Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.127+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=30 volume=22 Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.127+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.127+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_STOPPED positionMs=30 volume=22 Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 21ms Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 21ms Apr 11 10:32:08 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:08.128+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Radio Paradise Main Mix (FLAC)" Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: info: Apr 11 10:32:08 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:08 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 21ms Apr 11 10:32:08 volumio volumio[1268]: info: sendMpdCommand play took 17 milliseconds Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 3ms Apr 11 10:32:08 volumio volumio[1268]: info: ------------------------------ 2ms Apr 11 10:32:08 volumio volumio[1268]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Apr 11 10:32:08 volumio volumio[1268]: info: [1775871128133] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Apr 11 10:32:08 volumio volumio[1268]: info: [1775871128637] [RadioParadise] received new metadata: {"time":112,"artist":"breathe.","title":"Darkest Days","album":"Darkest Days","year":"2024","cover":"https://img.radioparadise.com/covers/l/24211.jpg","cover_med":"https://img.radioparadise.com/covers/m/24211.jpg","cover_small":"https://img.radioparadise.com/covers/s/24211.jpg"} Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/24211.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Darkest Days","title":"Darkest Days","artist":"breathe.","album":"Darkest Days","streaming":true,"disableUiControls":true,"duration":117,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 11 10:32:08 volumio volumio[1268]: verbose: CURRENT POSITION 25 Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Apr 11 10:32:08 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus stop Apr 11 10:32:08 volumio volumio[1268]: info: [1775871128641] [RadioParadise] setting new timer with duration of 117000 seconds. Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:08 volumio volumio[1268]: info: CorePlayQueue::getTrack 10 Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 6 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 6 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 15ms Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 14ms Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 12ms Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 11ms Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 12ms Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 10ms Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:10 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:10 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:10 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:10 volumio volumio[1268]: info: Apr 11 10:32:10 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 6ms Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 4ms Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 4 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 5ms Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 11ms Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 9ms Apr 11 10:32:10 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 10:32:10 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:10 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:10 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:10 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:10 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:10 volumio volumio[1268]: info: ------------------------------ 8ms Apr 11 10:32:14 volumio volumio[1268]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 10:32:14 volumio volumio[1268]: info: CoreCommandRouter::Close All Modals sent Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:15 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:15 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Apr 11 10:32:15 volumio volumio[1268]: info: Ignoring MPD Status Update Apr 11 10:32:15 volumio volumio[1268]: info: Apr 11 10:32:15 volumio volumio[1268]: ---------------------------- MPD announces state update: player Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::getState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 4ms Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 4ms Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand status took 5 milliseconds Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 4ms Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand status took 3 milliseconds Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseState Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:15 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:15 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:15 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 8ms Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 10:32:15 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:15 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:15 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:15 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:15 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:15 volumio volumio[1268]: info: ControllerMpd::pushState Apr 11 10:32:15 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Apr 11 10:32:15 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:15 volumio volumio[1268]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 13ms Apr 11 10:32:15 volumio volumio[1268]: info: ------------------------------ 13ms Apr 11 10:32:33 volumio volumio[1268]: info: VolumeController::SetAlsaVolume49 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.063+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=24324 volume=49 Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.064+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioGetQueue Apr 11 10:32:33 volumio volumio[1268]: info: CoreStateMachine::getQueue Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getQueue Apr 11 10:32:33 volumio volumio[1268]: info: VolumeController::SetAlsaVolume52 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.463+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=24575 volume=52 Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.463+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:32:33 volumio volumio[1268]: info: VolumeController::SetAlsaVolume50 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:33 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:33 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.781+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=25075 volume=50 Apr 11 10:32:33 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:33.782+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:32:35 volumio volumio[1268]: info: VolumeController::SetAlsaVolume42 Apr 11 10:32:35 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:35 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:35 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:35 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:35 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:35 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:35 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:35.293+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=26576 volume=42 Apr 11 10:32:35 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:35.293+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:32:54 volumio volumio[1268]: info: VolumeController::SetAlsaVolume41 Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:54 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:54 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:54 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:54 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:54.606+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=45847 volume=41 Apr 11 10:32:54 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:54.606+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:32:54 volumio volumio[1268]: info: VolumeController::SetAlsaVolume28 Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Apr 11 10:32:54 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:54 volumio volumio[1268]: info: CoreStateMachine::pushState Apr 11 10:32:54 volumio volumio[1268]: info: CorePlayQueue::getTrack 25 Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 10:32:54 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Apr 11 10:32:54 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:54.947+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" state=STATUS_PLAYING positionMs=46097 volume=28 Apr 11 10:32:54 volumio volumio5-onboarding[1690]: time=2026-04-11T10:32:54.948+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.109:32868 @ 0x1e50810" id=https://stream.radioparadise.com/flacm title="Darkest Days" Apr 11 10:33:11 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Apr 11 10:33:11 volumio volumio[1268]: info: Preload queue cleared Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: https://stream.radioparadise.com/flacm Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/John_Denver-take_me_home_country_road__aybabtu__.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/CinemaParadiso-love theme.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Beatles-I Will.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Sting_- Englishman in New York.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Debby boone - You light up my life.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Frank_sinatra_-_i_did_it_my_way.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Don mclean - Vincent.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Smokie_-_Living_Next_Door_To_Alice.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Beatles-Hey Jude.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Mariah Carey - Hero.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Eric_Clapton_WonderfulTonight.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Boys II Men - hard to say.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Eagles - Hotel California.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Simon And Garfunkel - The Boxer.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/ABBA - Dancing Queen.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Backstreet_boys_-_as_long_as_you_love_me.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Aqua - sound1.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Alan_Parsons_project-Some_other_time.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Air Supply-Making_Love_Out_Of_Nothing_At_All.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/ABBA - I have a dream.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/USB1/Pop 명곡/Billy Joel - Honesty.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: http://stream.radioparadise.com/global-flacm Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/클래식/CD_02 J. S. Bach - The Violin Concertos/03 J.S. Bach - Violin Concerto in A Minor, BWV 1041 III. Allegro.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/클래식/CD_02 J. S. Bach - The Violin Concertos/02 J.S. Bach - Violin Concerto in A Minor, BWV 1041 II. Adagio.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/클래식/CD_02 J. S. Bach - The Violin Concertos/01 J.S. Bach - Violin Concerto in A Minor, BWV 1041 I. Allegro.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/클래식/CD_01 Shubert & Grieg/01 Shubert - Arpeggione Sonata in a Minor D.821 mov 1.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/아빠가 좋아하는 노래/연가/01. 하나의 사랑 - 추성훈.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Preloading song: mnt/USB/HK/노래/One Direction/Made In The A.M. [Deluxe Edition]/01 One Direction - Hey Angel.mp3 Apr 11 10:33:11 volumio volumio[1268]: info: Exploding uri https://stream.radioparadise.com/flacm in service radio_paradise Apr 11 10:33:11 volumio volumio[1268]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/flacm" Apr 11 10:33:11 volumio volumio[1268]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 10:33:11 volumio volumio[1268]: TypeError: Cannot read properties of undefined (reading 'then') Apr 11 10:33:11 volumio volumio[1268]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Apr 11 10:33:11 volumio volumio[1268]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 11 10:33:11 volumio volumio[1268]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 11 10:33:11 volumio volumio[1268]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Apr 11 10:33:11 volumio volumio[1268]: at listOnTimeout (node:internal/timers:573:17) Apr 11 10:33:11 volumio volumio[1268]: at process.processTimers (node:internal/timers:514:7) Apr 11 10:33:11 volumio volumio[1268]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 10:33:12 volumio sudo[3240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 10:32' Apr 11 10:33:12 volumio sudo[3240]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"