Oct 13 00:21:07 rivoplus systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 13 00:21:09 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 00:21:09 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 00:21:10 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:10 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 18000ms Oct 13 00:21:18 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::ClearQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::stPlaybackTimer Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::serviceStop Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::serviceStop Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Oct 13 00:21:18 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService stop Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: No code Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: ControllerMpd::stop Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::clearPlayQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::addQueueItems Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::addQueueItems Oct 13 00:21:18 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:18 rivoplus volumio[3458]: info: Adding Item to queue: motherearthradio/radio/flac192 Oct 13 00:21:18 rivoplus volumio[3458]: info: Using cached record of: motherearthradio/radio/flac192 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPlay Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::play index 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::play index undefined Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::startPlaybackTimer Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 00:21:18 rivoplus volumio[3458]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Oct 13 00:21:18 rivoplus volumio[3458]: info: [MER] 🔌 Starting SSE for Radio Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand stop took 106 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand stop took 43 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 00:21:18 rivoplus volumio[3458]: info: Oct 13 00:21:18 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:18 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand clear took 5 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" Oct 13 00:21:18 rivoplus volumio[3458]: error: updateQueue error: null Oct 13 00:21:18 rivoplus volumio[3458]: info: Oct 13 00:21:18 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:18 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:18 rivoplus volumio[3458]: info: ------------------------------ 9ms Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 6 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand play Oct 13 00:21:18 rivoplus volumio[3458]: info: ------------------------------ 12ms Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand play took 9 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/radio/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} Oct 13 00:21:18 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService play Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:18 rivoplus volumio[3458]: info: Oct 13 00:21:18 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:18 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:18 rivoplus volumio[3458]: info: Oct 13 00:21:18 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:18 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:18 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand status took 8 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:18 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand status took 29 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 24 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 14 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:18 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:18 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:18 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:18 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:18 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:18 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:18 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:18 rivoplus volumio[3458]: info: ------------------------------ 103ms Oct 13 00:21:18 rivoplus volumio[3458]: info: ------------------------------ 101ms Oct 13 00:21:18 rivoplus volumio[3458]: info: MCU Signalled Playback Inactive Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:18 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:18 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 3000ms Oct 13 00:21:19 rivoplus volumio[3458]: info: MCU Signalled Playback Active Oct 13 00:21:20 rivoplus volumio[3458]: info: Executing endpoint metavolumio Oct 13 00:21:20 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 00:21:22 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:22 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 6000ms Oct 13 00:21:24 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::ClearQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::stPlaybackTimer Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::serviceStop Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::serviceStop Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Oct 13 00:21:24 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService stop Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: No code Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: ControllerMpd::stop Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::clearPlayQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::addQueueItems Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::addQueueItems Oct 13 00:21:24 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:24 rivoplus volumio[3458]: info: Adding Item to queue: motherearthradio/radio/flac192 Oct 13 00:21:24 rivoplus volumio[3458]: info: Using cached record of: motherearthradio/radio/flac192 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPlay Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::play index 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::play index undefined Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::startPlaybackTimer Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 00:21:24 rivoplus volumio[3458]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Oct 13 00:21:24 rivoplus volumio[3458]: info: [MER] 🔌 Starting SSE for Radio Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand stop took 112 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand stop took 51 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 00:21:24 rivoplus volumio[3458]: info: Oct 13 00:21:24 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:24 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand clear took 2 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" Oct 13 00:21:24 rivoplus volumio[3458]: error: updateQueue error: null Oct 13 00:21:24 rivoplus volumio[3458]: info: Oct 13 00:21:24 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:24 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:24 rivoplus volumio[3458]: info: ------------------------------ 4ms Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 3 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand play Oct 13 00:21:24 rivoplus volumio[3458]: info: ------------------------------ 8ms Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand play took 6 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/radio/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} Oct 13 00:21:24 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService play Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:24 rivoplus volumio[3458]: info: Oct 13 00:21:24 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:24 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:24 rivoplus volumio[3458]: info: Oct 13 00:21:24 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:24 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:24 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand status took 7 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:24 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand status took 19 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 15 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:24 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:24 rivoplus volumio[3458]: info: ------------------------------ 45ms Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 23 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 13 00:21:24 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:24 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:24 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:24 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:24 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:24 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:24 rivoplus volumio[3458]: info: ------------------------------ 58ms Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:24 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:24 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 3000ms Oct 13 00:21:24 rivoplus volumio[3458]: info: MCU Signalled Playback Inactive Oct 13 00:21:24 rivoplus volumio[3458]: info: MCU Signalled Playback Active Oct 13 00:21:26 rivoplus volumio[3458]: info: Executing endpoint metavolumio Oct 13 00:21:26 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 00:21:27 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:27 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 6000ms Oct 13 00:21:33 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:34 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 9000ms Oct 13 00:21:37 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Oct 13 00:21:37 rivoplus volumio[3458]: info: {"name":"Radio (FLAC 96kHz/24bit)","uri":"https://stream.radioenergy.to/stream"} Oct 13 00:21:37 rivoplus volumio[3458]: info: Saving local item my-web-radio Oct 13 00:21:37 rivoplus volumio[3458]: info: Saving local item my-web-radio Oct 13 00:21:40 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::ClearQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::stPlaybackTimer Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::serviceStop Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::serviceStop Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Oct 13 00:21:40 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService stop Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: No code Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: ControllerMpd::stop Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::clearPlayQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::addQueueItems Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::addQueueItems Oct 13 00:21:40 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:21:40 rivoplus volumio[3458]: info: Adding Item to queue: https://stream.radioenergy.to/stream Oct 13 00:21:40 rivoplus volumio[3458]: info: Exploding uri https://stream.radioenergy.to/stream in service motherearthradio Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPlay Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::play index 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::play index undefined Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::startPlaybackTimer Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 00:21:40 rivoplus volumio[3458]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Oct 13 00:21:40 rivoplus volumio[3458]: info: [MER] 🔌 Starting SSE for Radio Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand stop took 89 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand stop took 31 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 00:21:40 rivoplus volumio[3458]: info: Oct 13 00:21:40 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:40 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand clear took 4 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" Oct 13 00:21:40 rivoplus volumio[3458]: error: updateQueue error: null Oct 13 00:21:40 rivoplus volumio[3458]: info: Oct 13 00:21:40 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:21:40 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:21:40 rivoplus volumio[3458]: info: ------------------------------ 10ms Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 8 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand play Oct 13 00:21:40 rivoplus volumio[3458]: info: ------------------------------ 10ms Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand play took 5 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"https://stream.radioenergy.to/stream","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} Oct 13 00:21:40 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService play Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:21:40 rivoplus volumio[3458]: info: Oct 13 00:21:40 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:40 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:40 rivoplus volumio[3458]: info: Oct 13 00:21:40 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:21:40 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:21:40 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand status took 37 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:40 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403 Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand status took 46 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 38 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:40 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:40 rivoplus volumio[3458]: info: ------------------------------ 103ms Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 48 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 13 00:21:40 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:21:40 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:21:40 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:21:40 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:21:40 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:21:40 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:21:40 rivoplus volumio[3458]: info: ------------------------------ 141ms Oct 13 00:21:40 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:40 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 3000ms Oct 13 00:21:40 rivoplus volumio[3458]: info: MCU Signalled Playback Inactive Oct 13 00:21:40 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:21:40 rivoplus volumio[3458]: info: MCU Signalled Playback Active Oct 13 00:21:42 rivoplus volumio[3458]: info: Executing endpoint metavolumio Oct 13 00:21:42 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 00:21:43 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:43 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 6000ms Oct 13 00:21:49 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:49 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 9000ms Oct 13 00:21:59 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:21:59 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 12000ms Oct 13 00:22:11 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:22:11 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 15000ms Oct 13 00:22:26 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:22:26 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 18000ms Oct 13 00:22:32 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Oct 13 00:22:32 rivoplus volumio[3458]: info: {"name":"Radio (FLAC 96kHz/24bit)","uri":"https://futura.fm/stream.ogg"} Oct 13 00:22:32 rivoplus volumio[3458]: info: Saving local item my-web-radio Oct 13 00:22:33 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::ClearQueue Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::stPlaybackTimer Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::serviceStop Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::serviceStop Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Oct 13 00:22:33 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService stop Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:33 rivoplus volumio[3458]: info: No code Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:33 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:33 rivoplus volumio[3458]: info: ControllerMpd::stop Oct 13 00:22:33 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::clearPlayQueue Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:22:33 rivoplus volumio[3458]: info: CoreStateMachine::addQueueItems Oct 13 00:22:33 rivoplus volumio[3458]: info: CorePlayQueue::addQueueItems Oct 13 00:22:33 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:22:33 rivoplus volumio[3458]: info: Adding Item to queue: motherearthradio/radio/aac Oct 13 00:22:33 rivoplus volumio[3458]: info: Exploding uri motherearthradio/radio/aac in service motherearthradio Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPlay Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::play index 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::play index undefined Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::startPlaybackTimer Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 00:22:34 rivoplus volumio[3458]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth.aac Oct 13 00:22:34 rivoplus volumio[3458]: info: [MER] 🔌 Starting SSE for Radio Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand stop took 117 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand stop took 51 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 00:22:34 rivoplus volumio[3458]: info: Oct 13 00:22:34 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:22:34 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand clear took 5 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth.aac" Oct 13 00:22:34 rivoplus volumio[3458]: error: updateQueue error: null Oct 13 00:22:34 rivoplus volumio[3458]: info: Oct 13 00:22:34 rivoplus volumio[3458]: ---------------------------- MPD announces system playlist update Oct 13 00:22:34 rivoplus volumio[3458]: info: Ignoring MPD Status Update Oct 13 00:22:34 rivoplus volumio[3458]: info: ------------------------------ 5ms Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth.aac" took 4 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand play Oct 13 00:22:34 rivoplus volumio[3458]: info: ------------------------------ 12ms Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand play took 7 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"aac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · AAC 96kHz","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/radio/aac","streaming":true,"disableUiControls":true,"samplerate":"96 kHz","bitdepth":"","duration":0,"seek":0} Oct 13 00:22:34 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService play Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:22:34 rivoplus volumio[3458]: info: Oct 13 00:22:34 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:22:34 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:22:34 rivoplus volumio[3458]: info: Oct 13 00:22:34 rivoplus volumio[3458]: ---------------------------- MPD announces state update: player Oct 13 00:22:34 rivoplus volumio[3458]: info: ControllerMpd::getState Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand status Oct 13 00:22:34 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth.aac"; got HTTP status 403 Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand status took 29 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:22:34 rivoplus volumio[3458]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth.aac"; got HTTP status 403 Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand status took 31 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 25 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::parseState Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:22:34 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:34 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:22:34 rivoplus volumio[3458]: info: ------------------------------ 84ms Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand clearerror took 68 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 13 00:22:34 rivoplus volumio[3458]: verbose: ControllerMpd::parseTrackInfo Oct 13 00:22:34 rivoplus volumio[3458]: info: ControllerMpd::pushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:34 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:34 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:34 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:34 rivoplus volumio[3458]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Oct 13 00:22:34 rivoplus volumio[3458]: info: ------------------------------ 160ms Oct 13 00:22:34 rivoplus volumio[3458]: error: [MER] SSE connection failed: 403 Oct 13 00:22:34 rivoplus volumio[3458]: info: [MER] SSE: Reconnecting in 3000ms Oct 13 00:22:34 rivoplus volumio[3458]: info: MCU Signalled Playback Inactive Oct 13 00:22:34 rivoplus volumio[3458]: info: Updating RAAT Signal Path Oct 13 00:22:34 rivoplus volumio[3458]: info: MCU Signalled Playback Active Oct 13 00:22:36 rivoplus volumio[3458]: info: Executing endpoint metavolumio Oct 13 00:22:36 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 00:22:37 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::ClearQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::stPlaybackTimer Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::serviceStop Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::serviceStop Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::servicePushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Oct 13 00:22:37 rivoplus volumio[3458]: verbose: CURRENT POSITION 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::syncState stateService stop Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::syncState currentStatus stop Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:37 rivoplus volumio[3458]: info: No code Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::pushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushState Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output update for this device Oct 13 00:22:37 rivoplus volumio[3458]: info: MRS: Pushing multiroomSync output Oct 13 00:22:37 rivoplus volumio[3458]: info: ControllerMpd::stop Oct 13 00:22:37 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::clearPlayQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::addQueueItems Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::addQueueItems Oct 13 00:22:37 rivoplus volumio[3458]: info: Preload queue cleared Oct 13 00:22:37 rivoplus volumio[3458]: info: Adding Item to queue: motherearthradio/radio/mono192 Oct 13 00:22:37 rivoplus volumio[3458]: info: Using cached record of: motherearthradio/radio/mono192 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPushQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::saveQueue Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::updateTrackBlock Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrackBlock Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetState Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioPlay Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::play index 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::stop Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::play index undefined Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreStateMachine::startPlaybackTimer Oct 13 00:22:37 rivoplus volumio[3458]: info: CorePlayQueue::getTrack 0 Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 00:22:37 rivoplus volumio[3458]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 00:22:37 rivoplus volumio[3458]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth.mono Oct 13 00:22:37 rivoplus volumio[3458]: info: [MER] 🔌 Starting SSE for Radio Oct 13 00:22:37 rivoplus volumio[3458]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 00:22:37 rivoplus volumio[3458]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 00:22:37 rivoplus volumio[3458]: Error: socket hang up Oct 13 00:22:37 rivoplus volumio[3458]: at connResetException (node:internal/errors:720:14) Oct 13 00:22:37 rivoplus volumio[3458]: at TLSSocket.socketCloseListener (node:_http_client:468:25) Oct 13 00:22:37 rivoplus volumio[3458]: at TLSSocket.emit (node:events:526:35) Oct 13 00:22:37 rivoplus volumio[3458]: at node:net:337:12 Oct 13 00:22:37 rivoplus volumio[3458]: at TCP.done (node:_tls_wrap:631:7) { Oct 13 00:22:37 rivoplus volumio[3458]: code: 'ECONNRESET' Oct 13 00:22:37 rivoplus volumio[3458]: } Oct 13 00:22:37 rivoplus volumio[3458]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 00:22:38 rivoplus sudo[9237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 00:21' Oct 13 00:22:38 rivoplus sudo[9237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"