-- Logs begin at Sat 2025-05-24 17:25:30 EDT, end at Sun 2025-05-25 02:32:00 EDT. -- May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::stPlaybackTimer May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::updateTrackBlock May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrackBlock May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::serviceStop May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::serviceStop May 25 02:31:01 rivoplus volumio[29159]: info: ControllerMpd::stop May 25 02:31:01 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:01 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:01 rivoplus volumio[29159]: info: May 25 02:31:01 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:01 rivoplus volumio[29159]: info: sendMpdCommand stop took 23 milliseconds May 25 02:31:01 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:01 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":128,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:01 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService stop May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: No code May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:01 rivoplus volumio[29159]: info: sendMpdCommand status took 22 milliseconds May 25 02:31:01 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:01 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:01 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:01 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:01 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:01 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 02:31:01 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:01 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:01 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:01 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:01 rivoplus volumio[29159]: info: ------------------------------ 45ms May 25 02:31:01 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:02 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:02 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:02 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:02 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:02 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:02 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:02 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:04 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:04 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:04 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:04 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:04 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:04 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:04 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:05 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:05 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:05 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:05 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:05 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:06 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPlay May 25 02:31:06 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:06 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:06 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:06 rivoplus volumio[29159]: info: CoreStateMachine::startPlaybackTimer May 25 02:31:06 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:06 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetVisibleSources May 25 02:31:06 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:31:06 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 02:31:06 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:06 rivoplus volumio[29159]: info: sendMpdCommand stop took 2 milliseconds May 25 02:31:06 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand clear May 25 02:31:06 rivoplus volumio[29159]: info: May 25 02:31:06 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:06 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:06 rivoplus volumio[29159]: info: sendMpdCommand clear took 2 milliseconds May 25 02:31:06 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand consume 1 May 25 02:31:06 rivoplus volumio[29159]: error: updateQueue error: null May 25 02:31:06 rivoplus volumio[29159]: info: ------------------------------ 3ms May 25 02:31:06 rivoplus volumio[29159]: info: sendMpdCommand consume 1 took 2 milliseconds May 25 02:31:06 rivoplus volumio[29159]: info: [1748154666510] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac May 25 02:31:06 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" May 25 02:31:06 rivoplus volumio[29159]: info: May 25 02:31:06 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:06 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:06 rivoplus volumio[29159]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds May 25 02:31:06 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand play May 25 02:31:06 rivoplus volumio[29159]: info: ------------------------------ 6ms May 25 02:31:06 rivoplus volumio[29159]: info: sendMpdCommand play took 2 milliseconds May 25 02:31:06 rivoplus volumio[29159]: info: [1748154666519] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 May 25 02:31:06 rivoplus volumio[29159]: info: [1748154666887] [RadioParadise] received new metadata: {"time":114,"artist":"The Offspring","title":"Come Out and Play","album":"Smash","year":"1994","cover":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_med":"https://img.radioparadise.com/covers/m/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_small":"https://img.radioparadise.com/covers/s/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg"} May 25 02:31:06 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:06 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:06 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:06 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":119,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:06 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:06 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService play May 25 02:31:06 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:06 rivoplus volumio[29159]: info: [1748154666888] [RadioParadise] setting new timer with duration of 119000 seconds. May 25 02:31:07 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:07 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:07 rivoplus volumio[29159]: info: May 25 02:31:07 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand status took 12 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:07 rivoplus volumio[29159]: info: May 25 02:31:07 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:07 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:07 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:07 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:07 rivoplus volumio[29159]: info: ------------------------------ 18ms May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand status took 3 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:07 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:07 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:07 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:07 rivoplus volumio[29159]: info: ------------------------------ 7ms May 25 02:31:07 rivoplus volumio[29159]: info: May 25 02:31:07 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:07 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:07 rivoplus volumio[29159]: info: May 25 02:31:07 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:07 rivoplus volumio[29159]: info: ------------------------------ 3ms May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand status took 2 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:07 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 02:31:07 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:07 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:07 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:07 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:07 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:07 rivoplus volumio[29159]: info: ------------------------------ 10ms May 25 02:31:10 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:10 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:10 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:10 rivoplus volumio[29159]: info: [1748154670537] [RadioParadise] seek to 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::stPlaybackTimer May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::updateTrackBlock May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrackBlock May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::serviceStop May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::serviceStop May 25 02:31:11 rivoplus volumio[29159]: info: ControllerMpd::stop May 25 02:31:11 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:11 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:11 rivoplus volumio[29159]: info: sendMpdCommand stop took 22 milliseconds May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":119,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:11 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService stop May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: No code May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: May 25 02:31:11 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:11 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:11 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:11 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:11 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:11 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:11 rivoplus volumio[29159]: info: sendMpdCommand status took 16 milliseconds May 25 02:31:11 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:11 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:11 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 02:31:11 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:11 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:11 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:11 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:11 rivoplus volumio[29159]: info: ------------------------------ 27ms May 25 02:31:11 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:12 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:12 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:12 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:12 rivoplus volumio[29159]: info: CoreStateMachine::startPlaybackTimer May 25 02:31:12 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:12 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetVisibleSources May 25 02:31:12 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:31:12 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 02:31:12 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:12 rivoplus volumio[29159]: info: sendMpdCommand stop took 9 milliseconds May 25 02:31:12 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand clear May 25 02:31:12 rivoplus volumio[29159]: info: May 25 02:31:12 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:12 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:12 rivoplus volumio[29159]: info: sendMpdCommand clear took 1 milliseconds May 25 02:31:12 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand consume 1 May 25 02:31:12 rivoplus volumio[29159]: error: updateQueue error: null May 25 02:31:12 rivoplus volumio[29159]: info: ------------------------------ 3ms May 25 02:31:12 rivoplus volumio[29159]: info: sendMpdCommand consume 1 took 2 milliseconds May 25 02:31:12 rivoplus volumio[29159]: info: [1748154672342] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac May 25 02:31:12 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" May 25 02:31:12 rivoplus volumio[29159]: info: May 25 02:31:12 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:12 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:12 rivoplus volumio[29159]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds May 25 02:31:12 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand play May 25 02:31:12 rivoplus volumio[29159]: info: ------------------------------ 6ms May 25 02:31:12 rivoplus volumio[29159]: info: sendMpdCommand play took 3 milliseconds May 25 02:31:12 rivoplus volumio[29159]: info: [1748154672351] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 May 25 02:31:12 rivoplus volumio[29159]: info: [1748154672710] [RadioParadise] received new metadata: {"time":108,"artist":"The Offspring","title":"Come Out and Play","album":"Smash","year":"1994","cover":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_med":"https://img.radioparadise.com/covers/m/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_small":"https://img.radioparadise.com/covers/s/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg"} May 25 02:31:12 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:12 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:12 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:12 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":113,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:12 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:12 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService play May 25 02:31:12 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:12 rivoplus volumio[29159]: info: [1748154672717] [RadioParadise] setting new timer with duration of 113000 seconds. May 25 02:31:13 rivoplus volumio[29159]: info: May 25 02:31:13 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:13 rivoplus volumio[29159]: info: May 25 02:31:13 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand status took 34 milliseconds May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand status took 3 milliseconds May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:13 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:13 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:13 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:13 rivoplus volumio[29159]: info: ------------------------------ 42ms May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:13 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:13 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:13 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:13 rivoplus volumio[29159]: info: ------------------------------ 10ms May 25 02:31:13 rivoplus volumio[29159]: info: May 25 02:31:13 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:13 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:13 rivoplus volumio[29159]: info: May 25 02:31:13 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:13 rivoplus volumio[29159]: info: ------------------------------ 5ms May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand status took 4 milliseconds May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:13 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 02:31:13 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:13 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:13 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:13 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:13 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:13 rivoplus volumio[29159]: info: ------------------------------ 8ms May 25 02:31:15 rivoplus volumio[29159]: info: CoreCommandRouter::volumioNext May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::next May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::stPlaybackTimer May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::updateTrackBlock May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrackBlock May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::serviceStop May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::serviceStop May 25 02:31:16 rivoplus volumio[29159]: info: ControllerMpd::stop May 25 02:31:16 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:16 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:16 rivoplus volumio[29159]: info: May 25 02:31:16 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:16 rivoplus volumio[29159]: info: sendMpdCommand stop took 34 milliseconds May 25 02:31:16 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:16 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":113,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:16 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService stop May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: No code May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:16 rivoplus volumio[29159]: info: sendMpdCommand status took 20 milliseconds May 25 02:31:16 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:16 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:16 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:16 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:16 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:16 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 13 milliseconds May 25 02:31:16 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:16 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:16 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:16 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:16 rivoplus volumio[29159]: info: ------------------------------ 43ms May 25 02:31:16 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:20 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPlay May 25 02:31:20 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:20 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:20 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:20 rivoplus volumio[29159]: info: CoreStateMachine::startPlaybackTimer May 25 02:31:20 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:20 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetVisibleSources May 25 02:31:20 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:31:20 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 02:31:20 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:20 rivoplus volumio[29159]: info: sendMpdCommand stop took 1 milliseconds May 25 02:31:20 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand clear May 25 02:31:20 rivoplus volumio[29159]: info: May 25 02:31:20 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:20 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:20 rivoplus volumio[29159]: info: sendMpdCommand clear took 1 milliseconds May 25 02:31:20 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand consume 1 May 25 02:31:20 rivoplus volumio[29159]: error: updateQueue error: null May 25 02:31:20 rivoplus volumio[29159]: info: ------------------------------ 3ms May 25 02:31:20 rivoplus volumio[29159]: info: sendMpdCommand consume 1 took 2 milliseconds May 25 02:31:20 rivoplus volumio[29159]: info: [1748154680712] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac May 25 02:31:20 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" May 25 02:31:20 rivoplus volumio[29159]: info: May 25 02:31:20 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:20 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:20 rivoplus volumio[29159]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds May 25 02:31:20 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand play May 25 02:31:20 rivoplus volumio[29159]: info: ------------------------------ 5ms May 25 02:31:20 rivoplus volumio[29159]: info: sendMpdCommand play took 2 milliseconds May 25 02:31:20 rivoplus volumio[29159]: info: [1748154680719] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 May 25 02:31:21 rivoplus volumio[29159]: info: [1748154681083] [RadioParadise] received new metadata: {"time":99,"artist":"The Offspring","title":"Come Out and Play","album":"Smash","year":"1994","cover":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_med":"https://img.radioparadise.com/covers/m/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_small":"https://img.radioparadise.com/covers/s/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg"} May 25 02:31:21 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:21 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:21 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:21 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":104,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:21 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:21 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService play May 25 02:31:21 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:21 rivoplus volumio[29159]: info: [1748154681085] [RadioParadise] setting new timer with duration of 104000 seconds. May 25 02:31:21 rivoplus volumio[29159]: info: May 25 02:31:21 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:21 rivoplus volumio[29159]: info: May 25 02:31:21 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand status took 16 milliseconds May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand status took 3 milliseconds May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:21 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:21 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:21 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:21 rivoplus volumio[29159]: info: ------------------------------ 24ms May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:21 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:21 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:21 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:21 rivoplus volumio[29159]: info: ------------------------------ 10ms May 25 02:31:21 rivoplus volumio[29159]: info: May 25 02:31:21 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:21 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:21 rivoplus volumio[29159]: info: May 25 02:31:21 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:21 rivoplus volumio[29159]: info: ------------------------------ 8ms May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand status took 7 milliseconds May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:21 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 02:31:21 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:21 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:21 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:21 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:21 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:21 rivoplus volumio[29159]: info: ------------------------------ 12ms May 25 02:31:25 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPlay May 25 02:31:25 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:25 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:25 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:27 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:27 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:27 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:27 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:27 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:27 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:27 rivoplus go-librespot[29496]: time="2025-05-25T02:31:27-04:00" level=trace msg="sent dealer ping" May 25 02:31:27 rivoplus go-librespot[29496]: time="2025-05-25T02:31:27-04:00" level=trace msg="received dealer pong" May 25 02:31:33 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:33 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:34 rivoplus go-librespot[29496]: time="2025-05-25T02:31:34-04:00" level=trace msg="received accesspoint ping" May 25 02:31:34 rivoplus go-librespot[29496]: time="2025-05-25T02:31:34-04:00" level=trace msg="received accesspoint pong ack" May 25 02:31:34 rivoplus sudo[26820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 02:31:34 rivoplus sudo[26820]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 02:31:34 rivoplus sudo[26820]: pam_unix(sudo:session): session closed for user root May 25 02:31:34 rivoplus sudo[26824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 02:31:34 rivoplus sudo[26824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 02:31:34 rivoplus sudo[26824]: pam_unix(sudo:session): session closed for user root May 25 02:31:34 rivoplus volumio[29159]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 13; SM-S136DL Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 25 02:31:34 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 02:31:34 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 02:31:35 rivoplus sudo[26828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 02:31:35 rivoplus sudo[26828]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 02:31:35 rivoplus sudo[26828]: pam_unix(sudo:session): session closed for user root May 25 02:31:35 rivoplus sudo[26832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 02:31:35 rivoplus sudo[26832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 02:31:35 rivoplus sudo[26832]: pam_unix(sudo:session): session closed for user root May 25 02:31:35 rivoplus volumio[29159]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 13; SM-S136DL Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetVisibleSources May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:35 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 02:31:35 rivoplus volumio[29159]: info: Received Get System Info May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:35 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:35 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:35 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:35 rivoplus volumio[29159]: info: Listing playlists May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 02:31:35 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 02:31:37 rivoplus volumio[29159]: info: Received Get System Info May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:37 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:37 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 02:31:37 rivoplus volumio[29159]: info: Received Get System Info May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:37 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:37 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:37 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:45 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 02:31:53 rivoplus volumio[29159]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 02:31:53 rivoplus volumio[29159]: info: Received Get System Version May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 02:31:53 rivoplus volumio[29159]: info: Received Get System Info May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:53 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:53 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:53 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:56 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:56 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:56 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:56 rivoplus volumio[29159]: info: [1748154716828] [RadioParadise] seek to 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 02:31:57 rivoplus volumio[29159]: info: Discovery: Getting this device information May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::stop May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::stPlaybackTimer May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::updateTrackBlock May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrackBlock May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:57 rivoplus go-librespot[29496]: time="2025-05-25T02:31:57-04:00" level=trace msg="sent dealer ping" May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::serviceStop May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::serviceStop May 25 02:31:57 rivoplus volumio[29159]: info: ControllerMpd::stop May 25 02:31:57 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:57 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:57 rivoplus go-librespot[29496]: time="2025-05-25T02:31:57-04:00" level=trace msg="received dealer pong" May 25 02:31:57 rivoplus volumio[29159]: info: May 25 02:31:57 rivoplus volumio[29159]: ---------------------------- MPD announces state update: player May 25 02:31:57 rivoplus volumio[29159]: info: sendMpdCommand stop took 34 milliseconds May 25 02:31:57 rivoplus volumio[29159]: info: ControllerMpd::getState May 25 02:31:57 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand status May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":104,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:57 rivoplus volumio[29159]: verbose: CURRENT POSITION 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService stop May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: No code May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: sendMpdCommand status took 20 milliseconds May 25 02:31:57 rivoplus volumio[29159]: verbose: ControllerMpd::parseState May 25 02:31:57 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 02:31:57 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:57 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:57 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:57 rivoplus volumio[29159]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 02:31:57 rivoplus volumio[29159]: verbose: ControllerMpd::parseTrackInfo May 25 02:31:57 rivoplus volumio[29159]: info: ControllerMpd::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreStateMachine::pushState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPushState May 25 02:31:57 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetState May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:57 rivoplus volumio[29159]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 25 02:31:57 rivoplus volumio[29159]: info: ------------------------------ 44ms May 25 02:31:57 rivoplus volumio[29159]: info: Updating RAAT Signal Path May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::play index undefined May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 02:31:58 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::startPlaybackTimer May 25 02:31:58 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 0 May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::volumioGetVisibleSources May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 02:31:58 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand stop May 25 02:31:58 rivoplus volumio[29159]: info: sendMpdCommand stop took 1 milliseconds May 25 02:31:58 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand clear May 25 02:31:58 rivoplus volumio[29159]: info: May 25 02:31:58 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:58 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:58 rivoplus volumio[29159]: info: sendMpdCommand clear took 1 milliseconds May 25 02:31:58 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand consume 1 May 25 02:31:58 rivoplus volumio[29159]: error: updateQueue error: null May 25 02:31:58 rivoplus volumio[29159]: info: ------------------------------ 3ms May 25 02:31:58 rivoplus volumio[29159]: info: sendMpdCommand consume 1 took 2 milliseconds May 25 02:31:58 rivoplus volumio[29159]: info: [1748154718085] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac May 25 02:31:58 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" May 25 02:31:58 rivoplus volumio[29159]: info: May 25 02:31:58 rivoplus volumio[29159]: ---------------------------- MPD announces system playlist update May 25 02:31:58 rivoplus volumio[29159]: info: Ignoring MPD Status Update May 25 02:31:58 rivoplus volumio[29159]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds May 25 02:31:58 rivoplus volumio[29159]: verbose: ControllerMpd::sendMpdCommand play May 25 02:31:58 rivoplus volumio[29159]: info: ------------------------------ 5ms May 25 02:31:58 rivoplus volumio[29159]: info: sendMpdCommand play took 3 milliseconds May 25 02:31:58 rivoplus volumio[29159]: info: [1748154718092] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::updateTrackBlock May 25 02:31:58 rivoplus volumio[29159]: info: CorePlayQueue::getTrackBlock May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::serviceClearAddPlay May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::serviceClearAddPlayTracks May 25 02:31:58 rivoplus volumio[29159]: error: WARNING: No clearAddPlayTracks method for service radio_paradise May 25 02:31:58 rivoplus volumio[29159]: info: [1748154718449] [RadioParadise] received new metadata: {"time":62,"artist":"The Offspring","title":"Come Out and Play","album":"Smash","year":"1994","cover":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_med":"https://img.radioparadise.com/covers/m/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","cover_small":"https://img.radioparadise.com/covers/s/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg"} May 25 02:31:58 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:58 rivoplus volumio[29159]: info: CoreCommandRouter::servicePushState May 25 02:31:58 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:58 rivoplus volumio[29159]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19737_e238fb70-4a83-4893-8c56-65e642392f48.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Come Out and Play","title":"Come Out and Play","artist":"The Offspring","album":"Smash","streaming":true,"disableUiControls":true,"duration":67,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 25 02:31:58 rivoplus volumio[29159]: verbose: CURRENT POSITION 1 May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::syncState stateService play May 25 02:31:58 rivoplus volumio[29159]: info: CoreStateMachine::syncState currentStatus stop May 25 02:31:58 rivoplus volumio[29159]: info: [1748154718451] [RadioParadise] setting new timer with duration of 67000 seconds. May 25 02:31:59 rivoplus volumio[29159]: info: CoreCommandRouter::volumioPrevious May 25 02:31:59 rivoplus volumio[29159]: info: CoreStateMachine::previous May 25 02:31:59 rivoplus volumio[29159]: info: CorePlayQueue::getTrack 1 May 25 02:31:59 rivoplus volumio[29159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 02:31:59 rivoplus volumio[29159]: TypeError: Cannot read property 'service' of undefined May 25 02:31:59 rivoplus volumio[29159]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1325:97) May 25 02:31:59 rivoplus volumio[29159]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) May 25 02:31:59 rivoplus volumio[29159]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) May 25 02:31:59 rivoplus volumio[29159]: at Socket.emit (events.js:400:28) May 25 02:31:59 rivoplus volumio[29159]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 25 02:31:59 rivoplus volumio[29159]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 25 02:31:59 rivoplus volumio[29159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 02:32:00 rivoplus sudo[26883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 02:31 May 25 02:32:00 rivoplus sudo[26883]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:04:38 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="0064c1a7ac4363e567dacab062cb5da8"