May 25 10:59:00 volumio volumio[1197]: info: [MER] 🎵 Tom Waits - Dirt In The Ground May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:00 volumio volumio[1197]: info: [MER] 📤 Tom Waits - Dirt In The Ground May 25 10:59:00 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:00 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth/art/054dca93806c2a4b93661818-1772207887.jpg","uri":"motherearthradio/radio/flac192","name":"Dirt In The Ground","title":"Dirt In The Ground","artist":"Tom Waits","album":"Glitter And Doom Live","streaming":true,"disableUiControls":true,"duration":319,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 25 10:59:00 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 10:59:00 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 10:59:00 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus play May 25 10:59:00 volumio volumio[1197]: info: Received an update from plugin. extracting info from payload May 25 10:59:00 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:00 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:59:00 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:00 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:00 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:00 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:00.945+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:00 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:00.946+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:00 volumio volumio[1197]: info: CorePlayQueue::getTrack 1 May 25 10:59:00 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:00.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:01 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:01.050+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:07 volumio volumio[1197]: info: CoreCommandRouter::volumioGetState May 25 10:59:07 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: Preload queue cleared May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::ClearQueue May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::stop May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::stPlaybackTimer May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::serviceStop May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::serviceStop May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 25 10:59:19 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::syncState stateService stop May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:19 volumio volumio[1197]: info: No code May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::stop May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::clearPlayQueue May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::addQueueItems May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::addQueueItems May 25 10:59:19 volumio volumio[1197]: info: Preload queue cleared May 25 10:59:19 volumio volumio[1197]: info: Adding Item to queue: motherearthradio/klassik/flac192 May 25 10:59:19 volumio volumio[1197]: info: Exploding uri motherearthradio/klassik/flac192 in service motherearthradio May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.183+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.183+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.184+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.185+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::volumioPlay May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::play index 0 May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::stop May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::play index undefined May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::startPlaybackTimer May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik May 25 10:59:19 volumio volumio[1197]: info: [MER] 🔌 Starting SSE for Klassik May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 10:59:19 volumio volumio[1197]: info: May 25 10:59:19 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand stop took 25 milliseconds May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::getState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand stop took 6 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand clear May 25 10:59:19 volumio volumio[1197]: info: May 25 10:59:19 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 10:59:19 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand clear took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik" May 25 10:59:19 volumio volumio[1197]: error: updateQueue error: null May 25 10:59:19 volumio volumio[1197]: info: May 25 10:59:19 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 10:59:19 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 10:59:19 volumio volumio[1197]: info: ------------------------------ 2ms May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik" took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand play May 25 10:59:19 volumio volumio[1197]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 10:59:19 volumio volumio[1197]: info: ------------------------------ 7ms May 25 10:59:19 volumio volumio[1197]: info: ------------------------------ 3ms May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand play took 2 milliseconds May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Klassik · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/klassik/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 25 10:59:19 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 10:59:19 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.230+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.290+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:19 volumio volumio[1197]: info: May 25 10:59:19 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::getState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 10:59:19 volumio volumio[1197]: info: May 25 10:59:19 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::getState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand status took 2 milliseconds May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::pushState May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 10:59:19 volumio volumio[1197]: info: ------------------------------ 6ms May 25 10:59:19 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 10:59:19 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 10:59:19 volumio volumio[1197]: info: ControllerMpd::pushState May 25 10:59:19 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:19 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:19 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 10:59:19 volumio volumio[1197]: info: ------------------------------ 4ms May 25 10:59:19 volumio volumio[1197]: info: [MER] ✅ SSE connected May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.350+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:19 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:19.410+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/radio/flac192 title="Dirt In The Ground" May 25 10:59:20 volumio volumio[1197]: info: May 25 10:59:20 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 10:59:20 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 10:59:20 volumio volumio[1197]: info: May 25 10:59:20 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 10:59:20 volumio volumio[1197]: info: ControllerMpd::getState May 25 10:59:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 10:59:20 volumio volumio[1197]: info: ------------------------------ 2ms May 25 10:59:20 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 10:59:20 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 10:59:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 10:59:20 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 0 milliseconds May 25 10:59:20 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 10:59:20 volumio volumio[1197]: info: ControllerMpd::pushState May 25 10:59:20 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:20 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 10:59:20 volumio volumio[1197]: info: ------------------------------ 2ms May 25 10:59:21 volumio volumio[1197]: info: [MER] 🎵 Manuel Barrueco - Granados Andaluza May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:21 volumio volumio[1197]: info: [MER] 📤 Manuel Barrueco - Granados Andaluza May 25 10:59:21 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:21 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_klassik/art/25859f60ac718154c6e3ec11-1772207777.jpg","uri":"motherearthradio/klassik/flac192","name":"Granados Andaluza","title":"Granados Andaluza","artist":"Manuel Barrueco","album":"Gitarrenwerke","streaming":true,"disableUiControls":true,"duration":264,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 25 10:59:21 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 10:59:21 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 10:59:21 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus play May 25 10:59:21 volumio volumio[1197]: info: Received an update from plugin. extracting info from payload May 25 10:59:21 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:21 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:59:21 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:21 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:21 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:21 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:21.012+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:21 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:21.014+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 1 May 25 10:59:21 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:21.060+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Granados Andaluza" May 25 10:59:21 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:21.120+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Granados Andaluza" May 25 10:59:26 volumio volumio[1197]: info: CoreCommandRouter::volumioGetState May 25 10:59:26 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:50 volumio volumio[1197]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:59:50 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 25 10:59:50 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 25 10:59:50 volumio sudo[7066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 25 10:59:50 volumio sudo[7066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio sudo[7071]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 10:59:50 volumio sudo[7071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio sudo[7066]: pam_unix(sudo:session): session closed for user root May 25 10:59:50 volumio sudo[7071]: pam_unix(sudo:session): session closed for user root May 25 10:59:50 volumio sudo[7077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 10:59:50 volumio sudo[7077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 25 10:59:50 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 25 10:59:50 volumio sudo[7077]: pam_unix(sudo:session): session closed for user root May 25 10:59:50 volumio sudo[7089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 10:59:50 volumio sudo[7084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 10:59:50 volumio sudo[7084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio sudo[7089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio sudo[7084]: pam_unix(sudo:session): session closed for user root May 25 10:59:50 volumio sudo[7093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 10:59:50 volumio sudo[7093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 10:59:50 volumio sudo[7089]: pam_unix(sudo:session): session closed for user root May 25 10:59:50 volumio sudo[7093]: pam_unix(sudo:session): session closed for user root May 25 10:59:55 volumio volumio[1197]: info: [MER] 🎵 Liszt Ferenc Chamber Orchestra Janos Rolla - Bach - Contrapunctus 5-7 May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:55 volumio volumio[1197]: info: [MER] 📤 Liszt Ferenc Chamber Orchestra Janos Rolla - Bach - Contrapunctus 5-7 May 25 10:59:55 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:55 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_klassik/art/e1156099047572dfe2cde565-1772207769.jpg","uri":"motherearthradio/klassik/flac192","name":"Bach - Contrapunctus 5-7","title":"Bach - Contrapunctus 5-7","artist":"Liszt Ferenc Chamber Orchestra Janos Rolla","album":"Die Kunst Der Fuge","streaming":true,"disableUiControls":true,"duration":656,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 25 10:59:55 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 10:59:55 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 10:59:55 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus play May 25 10:59:55 volumio volumio[1197]: info: Received an update from plugin. extracting info from payload May 25 10:59:55 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:55 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:59:55 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:55 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:55 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 10:59:55 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:55.166+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:55 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:55.166+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 10:59:55 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:55.230+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 10:59:55 volumio volumio[1197]: info: CorePlayQueue::getTrack 1 May 25 10:59:55 volumio volumio5-onboarding[1600]: time=2026-05-25T10:59:55.290+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 10:59:56 volumio volumio[1197]: info: CoreCommandRouter::volumioGetState May 25 10:59:56 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::ClearQueue May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::stPlaybackTimer May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::serviceStop May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::serviceStop May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 25 11:00:15 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::syncState stateService stop May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:15 volumio volumio[1197]: info: No code May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::stop May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::clearPlayQueue May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::addQueueItems May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::addQueueItems May 25 11:00:15 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:15 volumio volumio[1197]: info: Adding Item to queue: motherearthradio/instrumental/flac192 May 25 11:00:15 volumio volumio[1197]: info: Exploding uri motherearthradio/instrumental/flac192 in service motherearthradio May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.352+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.352+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.353+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::volumioPlay May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::play index 0 May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::play index undefined May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::startPlaybackTimer May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental May 25 11:00:15 volumio volumio[1197]: info: [MER] 🔌 Starting SSE for Instrumental May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:15 volumio volumio[1197]: info: May 25 11:00:15 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand stop took 37 milliseconds May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand stop took 17 milliseconds May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:00:15 volumio volumio[1197]: info: May 25 11:00:15 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:15 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand clear took 1 milliseconds May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" May 25 11:00:15 volumio volumio[1197]: error: updateQueue error: null May 25 11:00:15 volumio volumio[1197]: info: May 25 11:00:15 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:15 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:15 volumio volumio[1197]: info: ------------------------------ 2ms May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_instrumental/motherearth.instrumental" took 1 milliseconds May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.391+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand play May 25 11:00:15 volumio volumio[1197]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:00:15 volumio volumio[1197]: info: ------------------------------ 9ms May 25 11:00:15 volumio volumio[1197]: info: ------------------------------ 6ms May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand play took 2 milliseconds May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: 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} May 25 11:00:15 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 11:00:15 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.510+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 11:00:15 volumio volumio[1197]: info: [MER] ✅ SSE connected May 25 11:00:15 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:15.570+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/klassik/flac192 title="Bach - Contrapunctus 5-7" May 25 11:00:15 volumio volumio[1197]: info: May 25 11:00:15 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:15 volumio volumio[1197]: info: May 25 11:00:15 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand status took 3 milliseconds May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:15 volumio volumio[1197]: info: ------------------------------ 6ms May 25 11:00:15 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:15 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:15 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:15 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:15 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:15 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:15 volumio volumio[1197]: info: ------------------------------ 6ms May 25 11:00:16 volumio volumio[1197]: info: [MER] 🎵 Esbjörn Svensson Trio - Tuesday Wonderland May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: info: [MER] 📤 Esbjörn Svensson Trio - Tuesday Wonderland May 25 11:00:16 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_instrumental/art/d42bbc083e7569406363f9b9-1772207918.jpg","uri":"motherearthradio/instrumental/flac192","name":"Tuesday Wonderland","title":"Tuesday Wonderland","artist":"Esbjörn Svensson Trio","album":"Live In Hamburg","streaming":true,"disableUiControls":true,"duration":806,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 25 11:00:16 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:16 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 11:00:16 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus play May 25 11:00:16 volumio volumio[1197]: info: Received an update from plugin. extracting info from payload May 25 11:00:16 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:16 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:16 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:16 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:16.224+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 11:00:16 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:16.225+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 11:00:16 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:16.260+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 1 May 25 11:00:16 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:16.321+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:16 volumio volumio[1197]: info: May 25 11:00:16 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:16 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:16 volumio volumio[1197]: info: May 25 11:00:16 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:16 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:16 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:16 volumio volumio[1197]: info: ------------------------------ 3ms May 25 11:00:16 volumio volumio[1197]: info: sendMpdCommand status took 3 milliseconds May 25 11:00:16 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:16 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:16 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 0 milliseconds May 25 11:00:16 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:16 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:16 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:16 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:16 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:16 volumio volumio[1197]: info: ------------------------------ 4ms May 25 11:00:20 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::ClearQueue May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::stPlaybackTimer May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::serviceStop May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::serviceStop May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 25 11:00:20 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::syncState stateService stop May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:20 volumio volumio[1197]: info: No code May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::stop May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::clearPlayQueue May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::addQueueItems May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::addQueueItems May 25 11:00:20 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:20 volumio volumio[1197]: info: Adding Item to queue: motherearthradio/jazz/flac192 May 25 11:00:20 volumio volumio[1197]: info: Exploding uri motherearthradio/jazz/flac192 in service motherearthradio May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.648+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.649+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.650+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.650+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::volumioPlay May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::play index 0 May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::play index undefined May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::startPlaybackTimer May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz May 25 11:00:20 volumio volumio[1197]: info: [MER] 🔌 Starting SSE for Jazz May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:20 volumio volumio[1197]: info: May 25 11:00:20 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand stop took 39 milliseconds May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand stop took 25 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:00:20 volumio volumio[1197]: info: May 25 11:00:20 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:20 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand status took 2 milliseconds May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand clear took 1 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz" May 25 11:00:20 volumio volumio[1197]: error: updateQueue error: null May 25 11:00:20 volumio volumio[1197]: info: May 25 11:00:20 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:20 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:20 volumio volumio[1197]: info: ------------------------------ 2ms May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz" took 2 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand play May 25 11:00:20 volumio volumio[1197]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:00:20 volumio volumio[1197]: info: ------------------------------ 7ms May 25 11:00:20 volumio volumio[1197]: info: ------------------------------ 5ms May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand play took 2 milliseconds May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Jazz · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/jazz/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 25 11:00:20 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 11:00:20 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.702+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.761+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:20 volumio volumio[1197]: info: [MER] ✅ SSE connected May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.820+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:20 volumio volumio[1197]: info: May 25 11:00:20 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand status took 3 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:20 volumio volumio[1197]: info: May 25 11:00:20 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:20 volumio volumio[1197]: info: ------------------------------ 5ms May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand status took 0 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:20 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:20 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:20 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:20 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:20 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:20 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:20 volumio volumio[1197]: info: ------------------------------ 2ms May 25 11:00:20 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:20.882+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/instrumental/flac192 title="Tuesday Wonderland" May 25 11:00:21 volumio volumio[1197]: info: May 25 11:00:21 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:21 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:21 volumio volumio[1197]: info: May 25 11:00:21 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:21 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:21 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:21 volumio volumio[1197]: info: ------------------------------ 4ms May 25 11:00:21 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:21 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:21 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:21 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:21 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:21 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:21 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:21 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:21 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:21 volumio volumio[1197]: info: ------------------------------ 2ms May 25 11:00:28 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::ClearQueue May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::stPlaybackTimer May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::serviceStop May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::serviceStop May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 25 11:00:28 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::syncState stateService stop May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:28 volumio volumio[1197]: info: No code May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:28 volumio volumio[1197]: info: ControllerMpd::stop May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::clearPlayQueue May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::addQueueItems May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::addQueueItems May 25 11:00:28 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:28 volumio volumio[1197]: info: Adding Item to queue: motherearthradio/jazz/flac96 May 25 11:00:28 volumio volumio[1197]: info: Exploding uri motherearthradio/jazz/flac96 in service motherearthradio May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.771+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.771+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.772+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.772+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::volumioPlay May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::play index 0 May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::play index undefined May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::startPlaybackTimer May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz.flac-lo May 25 11:00:28 volumio volumio[1197]: info: [MER] 🔌 Starting SSE for Jazz May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:28 volumio volumio[1197]: info: May 25 11:00:28 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand stop took 37 milliseconds May 25 11:00:28 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand stop took 21 milliseconds May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:00:28 volumio volumio[1197]: info: May 25 11:00:28 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:28 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand status took 2 milliseconds May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand clear took 1 milliseconds May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz.flac-lo" May 25 11:00:28 volumio volumio[1197]: error: updateQueue error: null May 25 11:00:28 volumio volumio[1197]: info: May 25 11:00:28 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:28 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:28 volumio volumio[1197]: info: ------------------------------ 3ms May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_jazz/motherearth.jazz.flac-lo" took 2 milliseconds May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:28 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand play May 25 11:00:28 volumio volumio[1197]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:00:28 volumio volumio[1197]: info: ------------------------------ 6ms May 25 11:00:28 volumio volumio[1197]: info: ------------------------------ 4ms May 25 11:00:28 volumio volumio[1197]: info: sendMpdCommand play took 2 milliseconds May 25 11:00:28 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:28 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:28 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Jazz · FLAC 96kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/jazz/flac96","streaming":true,"disableUiControls":true,"samplerate":"96 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 25 11:00:28 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 11:00:28 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.830+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac192 title=Jazz May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.891+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac192 title=Jazz May 25 11:00:28 volumio volumio[1197]: info: [MER] ✅ SSE connected May 25 11:00:28 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:28.951+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac192 title=Jazz May 25 11:00:29 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:29.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac192 title=Jazz May 25 11:00:29 volumio volumio[1197]: info: May 25 11:00:29 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:29 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:29 volumio volumio[1197]: info: May 25 11:00:29 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:29 volumio volumio[1197]: info: sendMpdCommand status took 3 milliseconds May 25 11:00:29 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:29 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:29 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:29 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:29 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:29 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:29 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:29 volumio volumio[1197]: info: ------------------------------ 6ms May 25 11:00:29 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:29 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:29 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:29 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:29 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:29 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:29 volumio volumio[1197]: info: ------------------------------ 3ms May 25 11:00:30 volumio volumio[1197]: info: May 25 11:00:30 volumio volumio[1197]: ---------------------------- MPD announces system playlist update May 25 11:00:30 volumio volumio[1197]: info: Ignoring MPD Status Update May 25 11:00:30 volumio volumio[1197]: info: May 25 11:00:30 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:30 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:30 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:30 volumio volumio[1197]: info: ------------------------------ 4ms May 25 11:00:30 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:30 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:30 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:30 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:00:30 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:30 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:30 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:30 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:30 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 11:00:30 volumio volumio[1197]: info: ------------------------------ 4ms May 25 11:00:33 volumio volumio[1197]: info: [MER] 🎵 Michael Wollny with Eric Schaefer & Christian Weber, Michael Wollny - Metzengerstein May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:33 volumio volumio[1197]: info: [MER] 📤 Michael Wollny with Eric Schaefer & Christian Weber, Michael Wollny - Metzengerstein May 25 11:00:33 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:33 volumio volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_jazz/art/9f4a80fcdb19d5e3351e68f3-1772207922.jpg","uri":"motherearthradio/jazz/flac96","name":"Metzengerstein","title":"Metzengerstein","artist":"Michael Wollny with Eric Schaefer & Christian Weber, Michael Wollny","album":"Nachtfahrten","streaming":true,"disableUiControls":true,"duration":176,"seek":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2} May 25 11:00:33 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:33 volumio volumio[1197]: info: CoreStateMachine::syncState stateService play May 25 11:00:33 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus play May 25 11:00:33 volumio volumio[1197]: info: Received an update from plugin. extracting info from payload May 25 11:00:33 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:33 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:33 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:33 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:33 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:33 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:33.334+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 11:00:33 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:33.334+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_PLAYING positionMs=0 volume=100 May 25 11:00:33 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:33.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:33 volumio volumio[1197]: info: CorePlayQueue::getTrack 1 May 25 11:00:33 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:33.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:37 volumio volumio[1197]: info: CoreCommandRouter::volumioGetState May 25 11:00:37 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:42 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri May 25 11:00:43 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:47 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::ClearQueue May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::stPlaybackTimer May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::serviceStop May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::serviceStop May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 25 11:00:47 volumio volumio[1197]: verbose: CURRENT POSITION 0 May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::syncState stateService stop May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::syncState currentStatus stop May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:47 volumio volumio[1197]: info: No code May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:47 volumio volumio[1197]: info: ControllerMpd::stop May 25 11:00:47 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::clearPlayQueue May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::addQueueItems May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::addQueueItems May 25 11:00:47 volumio volumio[1197]: info: Preload queue cleared May 25 11:00:47 volumio volumio[1197]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D42%22%2C%22name%22%3A%22Serenity%22%2C%22title%22%3A%22Serenity%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F42%2Fcover_512x512%2F0.jpg%22%7D May 25 11:00:47 volumio volumio[1197]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D42%22%2C%22name%22%3A%22Serenity%22%2C%22title%22%3A%22Serenity%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F42%2Fcover_512x512%2F0.jpg%22%7D in service rp2 May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.270+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.275+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.275+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushQueue May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::saveQueue May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::updateTrackBlock May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrackBlock May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPlay May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::play index 0 May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::stop May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::play index undefined May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::startPlaybackTimer May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: [rp2] clearAddPlayTrack: rp2/channel@id=42 May 25 11:00:47 volumio volumio[1197]: info: May 25 11:00:47 volumio volumio[1197]: ---------------------------- MPD announces state update: player May 25 11:00:47 volumio volumio[1197]: info: sendMpdCommand stop took 38 milliseconds May 25 11:00:47 volumio volumio[1197]: info: ControllerMpd::getState May 25 11:00:47 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand status May 25 11:00:47 volumio volumio[1197]: info: sendMpdCommand status took 1 milliseconds May 25 11:00:47 volumio volumio[1197]: verbose: ControllerMpd::parseState May 25 11:00:47 volumio volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:00:47 volumio volumio[1197]: info: sendMpdCommand playlistinfo took 0 milliseconds May 25 11:00:47 volumio volumio[1197]: verbose: ControllerMpd::parseTrackInfo May 25 11:00:47 volumio volumio[1197]: info: ControllerMpd::pushState May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::servicePushState May 25 11:00:47 volumio volumio[1197]: info: CoreStateMachine::pushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:00:47 volumio volumio[1197]: info: CoreCommandRouter::volumioPushState May 25 11:00:47 volumio volumio[1197]: info: CorePlayQueue::getTrack 0 May 25 11:00:47 volumio volumio[1197]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" state=STATUS_STOPPED positionMs=0 volume=100 May 25 11:00:47 volumio volumio[1197]: info: ------------------------------ 7ms May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.341+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.461+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.581+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id=motherearthradio/jazz/flac96 title=Metzengerstein May 25 11:00:47 volumio volumio5-onboarding[1600]: time=2026-05-25T11:00:47.701+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x14a4f00" id="rp2/channel@id=42" title=Serenity May 25 11:00:47 volumio volumio[1197]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=42&audio_type= May 25 11:00:48 volumio volumio[1197]: info: [rp2] Obtained block for channel "42" May 25 11:00:48 volumio volumio[1197]: info: [rp2] ------------- May 25 11:00:48 volumio volumio[1197]: info: [rp2] Block summary May 25 11:00:48 volumio volumio[1197]: info: [rp2] ------------- May 25 11:00:48 volumio volumio[1197]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/42/x/4245/4/b/4245-0.flac May 25 11:00:48 volumio volumio[1197]: info: [rp2] Tracks: May 25 11:00:48 volumio volumio[1197]: info: [rp2] 0. 41 G. Arae (14:42 | elapsed: 14m 38s) May 25 11:00:48 volumio volumio[1197]: info: [rp2] May 25 11:00:48 volumio volumio[1197]: verbose: [rp2] Current track scheduled playback vs. current time: 5/25/2026, 10:57:34 AM <-> 5/25/2026, 11:00:48 AM May 25 11:00:48 volumio volumio[1197]: info: [rp2] Going to start playback of current track at 3:14 (track position in stream: 14:38) May 25 11:00:48 volumio volumio[1197]: info: [rp2] Starting mpv May 25 11:00:48 volumio volumio[1197]: /bin/sh: 1: mpv: not found May 25 11:00:48 volumio volumio[1197]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version May 25 11:00:48 volumio volumio[1197]: /bin/sh: 1: mpv: not found May 25 11:00:48 volumio volumio[1197]: info: [rp2] [mpv] mpv process spawned May 25 11:00:48 volumio volumio[1197]: verbose: [rp2] Waiting for player event "playing"... May 25 11:00:48 volumio volumio[1197]: info: [rp2] [mpv] (PID: 7186) /bin/sh: 1: mpv: not found May 25 11:00:48 volumio volumio[1197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:00:48 volumio volumio[1197]: TypeError: Cannot read properties of null (reading 'reset') May 25 11:00:48 volumio volumio[1197]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 May 25 11:00:48 volumio volumio[1197]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) May 25 11:00:48 volumio volumio[1197]: at ChildProcess.emit (node:events:514:28) May 25 11:00:48 volumio volumio[1197]: at maybeClose (node:internal/child_process:1105:16) May 25 11:00:48 volumio volumio[1197]: at Socket. (node:internal/child_process:457:11) May 25 11:00:48 volumio volumio[1197]: at Socket.emit (node:events:514:28) May 25 11:00:48 volumio volumio[1197]: at Pipe. (node:net:337:12) May 25 11:00:48 volumio volumio[1197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:00:48 volumio sudo[7201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 10:59' May 25 11:00:48 volumio sudo[7201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"