Mar 24 20:29:35 volumio volumio[1254]: error: error Mar 24 20:29:35 volumio volumio[1254]: error: error Mar 24 20:29:35 volumio volumio[1254]: error: error Mar 24 20:30:07 volumio volumio[1254]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/motherearthradio/volumio/bookworm/armhf Mar 24 20:30:07 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:07.613+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:08 volumio volumio[1254]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/motherearthradio/volumio/bookworm/armhf Mar 24 20:30:08 volumio volumio[1254]: info: Folder /tmp/plugins removed Mar 24 20:30:08 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:08.972+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:08 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:08.996+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:08 volumio volumio[1254]: info: Check plugin dependencies Mar 24 20:30:09 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.013+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Checking if plugin already exists Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.017+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Rename folder Mar 24 20:30:09 volumio volumio[1254]: info: Folder /tmp/downloaded_plugin.zip removed Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.034+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Move to category Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.544+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Checking if install.sh is present Mar 24 20:30:09 volumio volumio[1254]: info: Executing install.sh Mar 24 20:30:09 volumio sudo[7461]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/motherearthradio/install.sh Mar 24 20:30:09 volumio sudo[7461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 20:30:09 volumio sudo[7461]: pam_unix(sudo:session): session closed for user root Mar 24 20:30:09 volumio volumio[1254]: info: Installing motherearthradio Dependencies Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.577+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: Plugin install end detected on script Mar 24 20:30:09 volumio volumio[1254]: info: Install script completed Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.584+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Adding reference to registry Mar 24 20:30:09 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:09.589+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:30:09 volumio volumio[1254]: info: Done installing plugin. Mar 24 20:30:09 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:30:09 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:30:09 volumio volumio[1254]: info: Folder /tmp/plugins removed Mar 24 20:30:09 volumio volumio[1254]: info: Folder /tmp/downloaded_plugin.zip removed Mar 24 20:30:09 volumio volumio[1254]: info: Folder /data/temp removed Mar 24 20:30:11 volumio volumio[1254]: info: Enabling plugin motherearthradio Mar 24 20:30:11 volumio volumio[1254]: info: Loading plugin "motherearthradio"... Mar 24 20:30:11 volumio volumio[1254]: info: PLUGIN START: motherearthradio Mar 24 20:30:11 volumio volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 20:30:11 volumio volumio[1254]: info: [1774380611940] CoreMusicLibrary::Adding element Mother Earth Radio Mar 24 20:30:11 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:30:11 volumio volumio[1254]: Cannot find translation for source Radio Paradise (RP2) Mar 24 20:30:11 volumio volumio[1254]: Cannot find translation for source Radio Paradise Mar 24 20:30:11 volumio volumio[1254]: Cannot find translation for source Mother Earth Radio Mar 24 20:30:11 volumio volumio[1254]: info: [MER] Plugin started (SSE mode) Mar 24 20:30:11 volumio volumio[1254]: info: Done. Mar 24 20:30:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Mar 24 20:30:20 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:22 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::stop Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::stPlaybackTimer Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::serviceStop Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::serviceStop Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Mar 24 20:30:22 volumio volumio[1254]: verbose: CURRENT POSITION 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::syncState stateService stop Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: No code Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::stop Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::saveQueue Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Mar 24 20:30:22 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:22 volumio volumio[1254]: info: Adding Item to queue: motherearthradio/radio/flac192 Mar 24 20:30:22 volumio volumio[1254]: info: Using cached record of: motherearthradio/radio/flac192 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.738+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.738+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.739+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.739+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.740+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.740+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.740+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.741+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.741+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.741+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.741+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.742+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.742+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.742+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.743+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.743+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::saveQueue Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Mar 24 20:30:22 volumio mpd[1652]: player: played "https://stream.motherearthradio.de/listen/motherearth/motherearth" Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::play index 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::stop Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::play index undefined Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth Mar 24 20:30:22 volumio volumio[1254]: info: [MER] 🔌 Starting SSE for Radio Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand stop took 46 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand stop took 11 milliseconds Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand clear took 6 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand status took 5 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand status took 4 milliseconds Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:22 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.796+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.796+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.796+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title=Radio Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.797+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title=Radio Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.798+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.798+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.798+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title=Radio Mar 24 20:30:22 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:22.799+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title=Radio Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 27ms Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 26ms Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: error: updateQueue error: null Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 29ms Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 28 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 6ms Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 7ms Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:22 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand play Mar 24 20:30:22 volumio volumio[1254]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 42ms Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: info: Mar 24 20:30:22 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:22 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 18ms Mar 24 20:30:22 volumio volumio[1254]: info: sendMpdCommand play took 13 milliseconds Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 12ms Mar 24 20:30:22 volumio volumio[1254]: info: ------------------------------ 11ms Mar 24 20:30:22 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:22 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:22 volumio volumio[1254]: 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} Mar 24 20:30:22 volumio volumio[1254]: verbose: CURRENT POSITION 0 Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Mar 24 20:30:22 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:30:23 volumio volumio[1254]: info: [MER] ✅ SSE connected Mar 24 20:30:23 volumio mpd[1652]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio mpd[1652]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio mpd[1652]: player: problems opening audio device while playing "https://stream.motherearthradio.de/listen/motherearth/motherearth" Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 7 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 7 milliseconds Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: info: Mar 24 20:30:23 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:23 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 14 milliseconds Mar 24 20:30:23 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 14 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand clearerror took 10 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 10 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand clearerror took 9 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 23ms Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand clearerror took 10 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand clearerror took 9 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 20:30:23 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:23 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 26ms Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 25ms Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 25ms Mar 24 20:30:23 volumio volumio[1254]: info: ------------------------------ 17ms Mar 24 20:30:29 volumio volumio[1254]: info: [MER] 🎵 Animanz and Juanita Euka - Drink The Water Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:29 volumio volumio[1254]: info: [MER] 📤 Animanz and Juanita Euka - Drink The Water Mar 24 20:30:29 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:29 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth/art/2fe711df2cb46305247b9dfe-1772207798.jpg","uri":"motherearthradio/radio/flac192","name":"Drink The Water","title":"Drink The Water","artist":"Animanz and Juanita Euka","album":"Exotic Other","streaming":true,"disableUiControls":true,"duration":212,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} Mar 24 20:30:29 volumio volumio[1254]: verbose: CURRENT POSITION 0 Mar 24 20:30:29 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Mar 24 20:30:29 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:30:29 volumio volumio[1254]: info: Received an update from plugin. extracting info from payload Mar 24 20:30:29 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:29 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:30:29 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:29 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:29 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.267+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.268+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.268+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.268+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.269+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.269+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.270+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:29 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:29.270+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:29 volumio volumio[1254]: info: CorePlayQueue::getTrack 1 Mar 24 20:30:30 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::stop Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::stPlaybackTimer Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::serviceStop Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::serviceStop Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Mar 24 20:30:30 volumio volumio[1254]: verbose: CURRENT POSITION 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::syncState stateService stop Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: No code Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::stop Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::saveQueue Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Mar 24 20:30:30 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:30 volumio volumio[1254]: info: Adding Item to queue: motherearthradio/instrumental/flac192 Mar 24 20:30:30 volumio volumio[1254]: info: Exploding uri motherearthradio/instrumental/flac192 in service motherearthradio Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.152+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.153+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.153+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.153+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.154+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.154+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.154+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.155+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.156+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.156+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.156+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.156+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.156+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.157+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.157+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.157+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/radio/flac192 title="Drink The Water" Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:30:30 volumio mpd[1652]: player: played "https://stream.motherearthradio.de/listen/motherearth/motherearth" Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::saveQueue Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::play index 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::stop Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::play index undefined Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental Mar 24 20:30:30 volumio volumio[1254]: info: [MER] 🔌 Starting SSE for Instrumental Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand stop took 43 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand stop took 11 milliseconds Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand clear took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 5 milliseconds Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.208+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/instrumental/flac192 title=Instrumental Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.208+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/instrumental/flac192 title=Instrumental Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.207+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.208+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.209+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:59594 @ 0x31023c0" id=motherearthradio/instrumental/flac192 title=Instrumental Mar 24 20:30:30 volumio volumio5-onboarding[1868]: time=2026-03-24T20:30:30.209+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.96:56668 @ 0x3420990" id=motherearthradio/instrumental/flac192 title=Instrumental Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: error: updateQueue error: null Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 21ms Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" took 21 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 4ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 3ms Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand play Mar 24 20:30:30 volumio volumio[1254]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 34ms Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces system playlist update Mar 24 20:30:30 volumio volumio[1254]: info: Ignoring MPD Status Update Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 16ms Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand play took 11 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 10ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 9ms Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Instrumental · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/instrumental/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} Mar 24 20:30:30 volumio volumio[1254]: verbose: CURRENT POSITION 0 Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::syncState stateService play Mar 24 20:30:30 volumio volumio[1254]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:30:30 volumio volumio[1254]: info: [MER] ✅ SSE connected Mar 24 20:30:30 volumio mpd[1652]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio mpd[1652]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:30 volumio mpd[1652]: player: problems opening audio device while playing "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: info: Mar 24 20:30:30 volumio volumio[1254]: ---------------------------- MPD announces state update: player Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::getState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:30:30 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 14 milliseconds Mar 24 20:30:30 volumio volumio[1254]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 14 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand clearerror took 9 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 10 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 9 milliseconds Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand status took 17 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand clearerror took 11 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand clearerror took 10 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseState Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 29ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 28ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 28ms Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:30 volumio volumio[1254]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 20:30:30 volumio volumio[1254]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:30:30 volumio volumio[1254]: info: ControllerMpd::pushState Mar 24 20:30:30 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Mar 24 20:30:30 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Mar 24 20:30:30 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Mar 24 20:30:30 volumio volumio[1254]: info: ------------------------------ 22ms Mar 24 20:30:35 volumio volumio[1254]: error: error Mar 24 20:30:35 volumio volumio[1254]: error: error Mar 24 20:30:35 volumio volumio[1254]: error: error Mar 24 20:30:41 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Mar 24 20:30:41 volumio volumio[1254]: info: Preload queue cleared Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$D Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/VA - Music Collection - Downtempo, Chillout, Lounge, Ambient, Relax, Electronic (2019)/001 After Sunrise - Ibiza Mood.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: motherearthradio/jazz/flac192 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: https://stream.radioparadise.com/flacm Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/16 - Ron and Marco - English Folksong.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/09 - Eva - Dream About You.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/01 - Plas Johnson - Pink Panther Theme.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/07 - Eleonora Holiday - The Devil May Care.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/05 - Wies Ingwersen - Wailin Wall.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/04 - Nat King Cole - Sunny Get Blue.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/Muki/VA - Siltech High-End Audiophile Test Demo CD, Vol.1 (2013, STS Digital)/02 - Philippe Elan - Tu es la.flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/09 - Jheena Lodwick - Too Young .flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/AA70-BD58/Music/Test CD/VA - Usher Audio Demonstation CD [2004-2009]/VA - Usher Audio Be There Volume 1 (2004) [FLAC] {Usher Audio Demonstation CD}/04 - TIMBRE - Moonlight .flac Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$33$0$1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$32$0$1$2 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$32$0$1$1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$32$0$1$0 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$23 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$22 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$21 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$20 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1F Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1E Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1D Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$11 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$3F Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$38 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$2A Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$18 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$15 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$14 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$12 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2D Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$13 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$7 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: motherearthradio/klassik/flac192 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$2 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$41 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$3E Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$22 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$21 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$15 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$D Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$0 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$0 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 06 - Четыре дня мечты.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 05 - Я люблю тебя очень.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 04 - Светлый ангел.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 03 - Все, что я искал.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 02 - О чем-то лучшем.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Vladimir Kuzmin - O chem-to Luchhem.sharelita.com/Кузьмин Владимир - 01 - Сказка о моей жизни.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Alexsin/#2_Za steklom/#2_16_Aleksandra (Karaoke).mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Alexsin/#2_Za steklom/#2_03_Piyanaya (Remix).mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Alexsin/#2_Za steklom/#2_02_Palchiki.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Alexsin/#2_Za steklom/#2_01_Karamelka (Remix).mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/2009 - Дикие травы/01 - Опасное лето.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Maksim_Moy_Ray_2007_MP3_320_kbps_[torrents.ru]/МакSим - 10. Звезда.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/ruki verch/34848-03.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/ruki verch/34848-02.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/ruki verch/34848-01.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Чи-Ли. Дискография. MP3/2008 - Сделано в Чили (Deluxe Edition)/Чи-Ли. 01 - Любовь - это яд.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: mnt/USB/18FD-7A3D/Music/rus/Чи-Ли. Дискография. MP3/Чи-Ли и Гоша Куценко/Чи-Ли и Гоша Куценко - Сказки.mp3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/TestCD-LinnSelektions-TheSuperAudioSurround3CD (2004-2007).por mertes de nnm-club.ru/TestCD-LinnSelektions-TheSuperAudioSurroundCD3 (2007).por mertes de nnm-club.ru/Various - Linn Records Super Audio Surround Sampler Vol 3.cue@0 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@8 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@7 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@6 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@13 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@12 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@10 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@9 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@5 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@4 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@3 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@2 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@0 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$5E Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$5D Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$58 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$3A Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$3E Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$80$1 Mar 24 20:30:41 volumio volumio[1254]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$9 Mar 24 20:30:41 volumio volumio[1254]: info: Exploding uri http://stream.radioparadise.com/beyond-flacm in service radio_paradise Mar 24 20:30:41 volumio volumio[1254]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/beyond-flacm" Mar 24 20:30:41 volumio volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:30:41 volumio volumio[1254]: TypeError: Cannot read properties of undefined (reading 'then') Mar 24 20:30:41 volumio volumio[1254]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Mar 24 20:30:41 volumio volumio[1254]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 24 20:30:41 volumio volumio[1254]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 24 20:30:41 volumio volumio[1254]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Mar 24 20:30:41 volumio volumio[1254]: at listOnTimeout (node:internal/timers:573:17) Mar 24 20:30:41 volumio volumio[1254]: at process.processTimers (node:internal/timers:514:7) Mar 24 20:30:41 volumio volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:30:42 volumio sudo[7536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 20:29' Mar 24 20:30:42 volumio sudo[7536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"