Jun 01 15:46:01 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:01 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:46:01 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:01 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323 Jun 01 15:46:01 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 15:46:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:01 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:01 volumio volumio[1184]: info: [1780308961181] ControllerWebradio::clearAddPlayTrack Jun 01 15:46:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:03 volumio kernel: usb 1-1.3: device descriptor read/8, error -110 Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 15:46:06 volumio volumio[1184]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 15:46:06 volumio volumio[1184]: info: Received Get System Version Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 15:46:06 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:06 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:10 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:10 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:46:10 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:10 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Jun 01 15:46:10 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 15:46:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:10 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:10 volumio volumio[1184]: info: [1780308970187] ControllerWebradio::clearAddPlayTrack Jun 01 15:46:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:18 volumio kernel: usb 1-1.3: device descriptor read/8, error -110 Jun 01 15:46:18 volumio volumio[1184]: info: VolumeController::SetAlsaVolume93 Jun 01 15:46:18 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:18 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:18 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:46:18 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:18 volumio kernel: usb 1-1-port3: unable to enumerate USB device Jun 01 15:46:18 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:18.977+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=8516 volume=93 Jun 01 15:46:18 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:18.978+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" title="ROCK ANTENNE" Jun 01 15:46:19 volumio volumio[1184]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '4'. Jun 01 15:46:19 volumio volumio[1184]: info: VolumeController::SetAlsaVolume98 Jun 01 15:46:19 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:19 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:19 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:46:19 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:19 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:19.952+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=9550 volume=98 Jun 01 15:46:19 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:19.954+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" title="ROCK ANTENNE" Jun 01 15:46:19 volumio volumio[1184]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '4'. Jun 01 15:46:20 volumio volumio[1184]: info: VolumeController::SetAlsaVolume100 Jun 01 15:46:20 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:20 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:20 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:46:20 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:20 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:20.055+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=9799 volume=100 Jun 01 15:46:20 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:20.056+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" title="ROCK ANTENNE" Jun 01 15:46:20 volumio volumio[1184]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '4'. Jun 01 15:46:24 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:24 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:24 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:46:24 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:24 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99574932 Jun 01 15:46:24 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 15:46:24 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:24 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:24 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:24 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:24 volumio volumio[1184]: info: [1780308984374] ControllerWebradio::clearAddPlayTrack Jun 01 15:46:24 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:32 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:32 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:32 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:32 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:32 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:32 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:32 volumio volumio[1184]: info: [1780308992226] ControllerWebradio::clearAddPlayTrack Jun 01 15:46:32 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:33 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:33 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:33 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:33 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:33 volumio volumio[1184]: info: [1780308993613] ControllerWebradio::clearAddPlayTrack Jun 01 15:46:33 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:38 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Jun 01 15:46:38 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:41 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:41 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:41 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:46:41 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:41 volumio volumio[1184]: info: Adding Item to queue: motherearthradio/radio/aac Jun 01 15:46:41 volumio volumio[1184]: info: Exploding uri motherearthradio/radio/aac in service motherearthradio Jun 01 15:46:41 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:41 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:41 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:41 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:41 volumio volumio[1184]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth.aac Jun 01 15:46:41 volumio volumio[1184]: info: [MER] 🔌 Starting SSE for Radio Jun 01 15:46:41 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:42 volumio volumio[1184]: info: [MER] ✅ SSE connected Jun 01 15:46:44 volumio volumio[1184]: info: [MER] 🎵 Fred Frith - The Border Jun 01 15:46:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:44 volumio volumio[1184]: info: [MER] 📤 Fred Frith - The Border Jun 01 15:46:44 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:46:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:44 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"aac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth/art/ed900b0673bb315e83c03d0f-1772207876.jpg","uri":"motherearthradio/radio/aac","name":"The Border","title":"The Border","artist":"Fred Frith","album":"Step Across The Border","streaming":true,"disableUiControls":true,"duration":212,"seek":0,"samplerate":"96 kHz","bitdepth":"","channels":2} Jun 01 15:46:44 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:46:44 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Jun 01 15:46:44 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:46:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:46:47 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-DISCONNECTED bssid=14:a7:2b:58:9d:f1 reason=3 locally_generated=1 Jun 01 15:46:47 volumio dhcpcd[788]: wlan0: carrier lost - roaming Jun 01 15:46:47 volumio dhcpcd[788]: wlan0: changing route to 192.168.1.0/24 Jun 01 15:46:47 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 01 15:46:47 volumio dhcpcd[788]: wlan0: changing default route via 192.168.1.1 Jun 01 15:46:47 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=IN Jun 01 15:46:47 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:47.939+05:30 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Jun 01 15:46:47 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:47 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:47 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 15:46:47 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: Trying to associate with 14:a7:2b:58:9d:f1 (SSID='FTTH' freq=2472 MHz) Jun 01 15:46:48 volumio wpa_supplicant[1141]: FT: Invalid key management type (2) Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: Associated with 14:a7:2b:58:9d:f1 Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: WPA: Key negotiation completed with 14:a7:2b:58:9d:f1 [PTK=CCMP GTK=TKIP] Jun 01 15:46:48 volumio wpa_supplicant[1141]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:a7:2b:58:9d:f1 completed [id=2 id_str=] Jun 01 15:46:48 volumio dhcpcd[788]: wlan0: carrier acquired Jun 01 15:46:48 volumio dhcpcd[788]: wlan0: IAID eb:69:61:be Jun 01 15:46:48 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::serviceStop Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::serviceStop Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Jun 01 15:46:48 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:48 volumio volumio[1184]: info: No code Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:46:48 volumio volumio[1184]: info: ControllerMpd::stop Jun 01 15:46:48 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:46:48 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:46:48 volumio volumio[1184]: info: Adding Item to queue: motherearthradio/klassik/mono192 Jun 01 15:46:48 volumio volumio[1184]: info: Exploding uri motherearthradio/klassik/mono192 in service motherearthradio Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.548+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.550+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/radio/aac title="The Border" Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.558+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.559+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/radio/aac title="The Border" Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.564+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.564+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/radio/aac title="The Border" Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.571+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.573+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/radio/aac title="The Border" Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.mono Jun 01 15:46:48 volumio volumio[1184]: info: [MER] 🔌 Starting SSE for Klassik Jun 01 15:46:48 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:46:48 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:48 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:48 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 15:46:48 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 15:46:48 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:48.830+05:30 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" available=true connected=true macAddress=b8:27:eb:69:61:be ip4Address=192.168.1.33/24 ip6Address= ssid=FTTH Jun 01 15:46:48 volumio dhcpcd[788]: wlan0: soliciting an IPv6 router Jun 01 15:46:49 volumio dhcpcd[788]: wlan0: rebinding lease of 192.168.1.33 Jun 01 15:46:49 volumio dhcpcd[788]: wlan0: leased 192.168.1.33 for 86400 seconds Jun 01 15:46:49 volumio dhcpcd[788]: wlan0: changing route to 192.168.1.0/24 Jun 01 15:46:49 volumio dhcpcd[788]: wlan0: changing default route via 192.168.1.1 Jun 01 15:46:49 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 15:46:49 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 01 15:46:49 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 15:46:49 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 15:46:49 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 15:46:49 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 15:46:49 volumio welcome[3049]: Resolved ip:[1] 192.168.1.33 Jun 01 15:46:49 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 15:46:49 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 01 15:46:49 volumio sudo[3053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 15:46:49 volumio sudo[3053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 15:46:49 volumio sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 15:46:49 volumio sudo[3053]: pam_unix(sudo:session): session closed for user root Jun 01 15:46:49 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 15:46:49 volumio sudo[3057]: pam_unix(sudo:session): session closed for user root Jun 01 15:46:49 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.35 UA: Mozilla/5.0 (Linux; Android 15; SM-A146B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 01 15:46:49 volumio volumio[1184]: info: [MER] ✅ SSE connected Jun 01 15:46:49 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:49.877+05:30 level=INFO msg="service successfully established" component=discovery/localnet Jun 01 15:46:49 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:49 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:49 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:49 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:49.940+05:30 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" available=true connected=true macAddress=b8:27:eb:69:61:be ip4Address=192.168.1.33/24 ip6Address= ssid=FTTH Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 15:46:49 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 15:46:50 volumio sudo[3062]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 15:46:50 volumio sudo[3062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 15:46:50 volumio sudo[3062]: pam_unix(sudo:session): session closed for user root Jun 01 15:46:50 volumio sudo[3064]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 15:46:50 volumio sudo[3064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 15:46:50 volumio sudo[3064]: pam_unix(sudo:session): session closed for user root Jun 01 15:46:50 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.35 UA: Mozilla/5.0 (Linux; Android 15; SM-A146B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:50 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::volumioGetQueue Jun 01 15:46:50 volumio volumio[1184]: info: CoreStateMachine::getQueue Jun 01 15:46:50 volumio volumio[1184]: info: CorePlayQueue::getQueue Jun 01 15:46:50 volumio volumio[1184]: info: Listing playlists Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 15:46:50 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:50 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:50 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:50 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 15:46:50 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 15:46:50 volumio volumio5-onboarding[1715]: time=2026-06-01T15:46:50.877+05:30 level=INFO msg="service successfully established" component=discovery/localnet Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 15:46:52 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:52 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:52 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:52 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 01 15:46:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 15:46:53 volumio volumio[1184]: info: Received Get System Info Jun 01 15:46:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:46:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:46:53 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:46:53 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:46:53 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:53 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:46:54 volumio volumio[1184]: info: [MER] 🎵 Manuel Barrueco - Albéniz Cuba Jun 01 15:46:54 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:54 volumio volumio[1184]: info: [MER] 📤 Manuel Barrueco - Albéniz Cuba Jun 01 15:46:54 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:46:54 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:54 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth_klassik/art/ddf6ae9d6ce832c58776becf-1772207780.jpg","uri":"motherearthradio/klassik/mono192","name":"Albéniz Cuba","title":"Albéniz Cuba","artist":"Manuel Barrueco","album":"Gitarrenwerke","streaming":true,"disableUiControls":true,"duration":514,"seek":0,"samplerate":"MONO/192 kHz","bitdepth":"24 bit","channels":1} Jun 01 15:46:54 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:46:54 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Jun 01 15:46:54 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:46:54 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:46:54 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 15:47:09 volumio volumio[1184]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 15:47:09 volumio volumio[1184]: info: Received Get System Version Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 15:47:09 volumio volumio[1184]: info: Received Get System Info Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 15:47:09 volumio volumio[1184]: info: Discovery: Getting this device information Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 01 15:47:09 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 15:47:11 volumio systemd[1]: mpd.service: State 'stop-sigterm' timed out. Killing. Jun 01 15:47:11 volumio systemd[1]: mpd.service: Killing process 1683 (mpd) with signal SIGKILL. Jun 01 15:47:11 volumio systemd[1]: mpd.service: Killing process 1734 (io) with signal SIGKILL. Jun 01 15:47:11 volumio systemd[1]: mpd.service: Killing process 2299 (update) with signal SIGKILL. Jun 01 15:47:11 volumio volumio[1184]: error: MPD error: Error: read ECONNRESET Jun 01 15:47:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Jun 01 15:47:11 volumio systemd[1]: mpd.service: Failed with result 'timeout'. Jun 01 15:47:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 15:47:11 volumio systemd[1]: mpd.service: Consumed 12.311s CPU time. Jun 01 15:47:11 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 15:47:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 15:47:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 15:47:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 15:47:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 15:47:12 volumio sudo[3098]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 15:47:12 volumio sudo[3098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 15:47:12 volumio sudo[3098]: pam_unix(sudo:session): session closed for user root Jun 01 15:47:13 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::serviceStop Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::serviceStop Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Jun 01 15:47:13 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:13 volumio volumio[1184]: info: No code Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:13 volumio volumio[1184]: info: ControllerMpd::stop Jun 01 15:47:13 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Jun 01 15:47:13 volumio volumio[1184]: info: Preload queue cleared Jun 01 15:47:13 volumio volumio[1184]: info: Adding Item to queue: motherearthradio/klassik/flac96 Jun 01 15:47:13 volumio volumio[1184]: info: Exploding uri motherearthradio/klassik/flac96 in service motherearthradio Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.743+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.745+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/mono192 title="Albéniz Cuba" Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.751+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.752+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/mono192 title="Albéniz Cuba" Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.758+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.759+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/mono192 title="Albéniz Cuba" Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.763+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:13 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:13.764+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/mono192 title="Albéniz Cuba" Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::saveQueue Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:47:13 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::play index 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:47:13 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:13 volumio volumio[1184]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo Jun 01 15:47:13 volumio volumio[1184]: info: [MER] 🔌 Starting SSE for Klassik Jun 01 15:47:13 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:15 volumio volumio[1184]: info: [MER] ✅ SSE connected Jun 01 15:47:17 volumio mpd[3101]: 2026-06-01T15:47:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 15:47:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 15:47:17 volumio volumio[1184]: error: updateQueue error: null Jun 01 15:47:17 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Jun 01 15:47:17 volumio volumio[1184]: error: updateQueue error: null Jun 01 15:47:29 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:29 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:29 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:29 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:29 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:29 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:29 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:30 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:30 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:30 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:30 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:30 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:30 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:30 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:33 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:33 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:33 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:33 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:33 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:33 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:33 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:34 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:34 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:34 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:34 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:34 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:34 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:34 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:35 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:47:35 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:35 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:35 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:35 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:47:35 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:35 volumio volumio[1184]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo Jun 01 15:47:35 volumio volumio[1184]: info: [MER] 🔌 Starting SSE for Klassik Jun 01 15:47:35 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:35 volumio volumio[1184]: info: sendMpdCommand stop took 5 milliseconds Jun 01 15:47:35 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 15:47:35 volumio volumio[1184]: info: Jun 01 15:47:35 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:35 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:35 volumio volumio[1184]: info: sendMpdCommand clear took 2 milliseconds Jun 01 15:47:35 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo" Jun 01 15:47:35 volumio volumio[1184]: info: Jun 01 15:47:35 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:35 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:35 volumio volumio[1184]: error: updateQueue error: null Jun 01 15:47:35 volumio volumio[1184]: info: Jun 01 15:47:35 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:35 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:35 volumio volumio[1184]: info: ------------------------------ 8ms Jun 01 15:47:35 volumio volumio[1184]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo" took 5 milliseconds Jun 01 15:47:35 volumio volumio[1184]: info: ------------------------------ 5ms Jun 01 15:47:35 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play Jun 01 15:47:35 volumio volumio[1184]: info: Jun 01 15:47:35 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:35 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:35 volumio volumio[1184]: info: ------------------------------ 23ms Jun 01 15:47:35 volumio volumio[1184]: info: sendMpdCommand play took 5 milliseconds Jun 01 15:47:35 volumio volumio[1184]: info: ------------------------------ 4ms Jun 01 15:47:35 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:35 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:35 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Klassik · FLAC 96kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/klassik/flac96","streaming":true,"disableUiControls":true,"samplerate":"96 kHz","bitdepth":"24 bit","duration":0,"seek":0} Jun 01 15:47:35 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:47:35 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Jun 01 15:47:35 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:47:36 volumio volumio[1184]: info: [MER] ✅ SSE connected Jun 01 15:47:38 volumio volumio[1184]: info: Jun 01 15:47:38 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:38 volumio volumio[1184]: info: Jun 01 15:47:38 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:38 volumio volumio[1184]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand status took 44 milliseconds Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:38 volumio volumio[1184]: info: Jun 01 15:47:38 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:38 volumio volumio[1184]: info: Jun 01 15:47:38 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:38 volumio volumio[1184]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand status took 66 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand clearerror took 27 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand status took 24 milliseconds Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:38 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:38 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:38 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:38 volumio volumio[1184]: info: ------------------------------ 84ms Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand status took 44 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand clearerror took 27 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:38 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:38 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:38 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:38 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:38 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:38 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:38 volumio volumio[1184]: info: ------------------------------ 102ms Jun 01 15:47:38 volumio volumio[1184]: info: ------------------------------ 57ms Jun 01 15:47:38 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 15:47:38 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:38 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:38 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:38 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:38 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:38 volumio volumio[1184]: info: ------------------------------ 57ms Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::serviceStop Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::serviceStop Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Jun 01 15:47:43 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:43 volumio volumio[1184]: info: No code Jun 01 15:47:43 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:43 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:43 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:43 volumio volumio[1184]: info: ControllerMpd::stop Jun 01 15:47:43 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.895+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.896+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.904+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.905+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.911+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.912+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.918+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:43 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:43.918+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand stop took 147 milliseconds Jun 01 15:47:44 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 1 Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:44 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand status took 6 milliseconds Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:44 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:44 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:44.066+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=196 volume=100 Jun 01 15:47:44 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:44.067+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 56ms Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:44 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:44 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:44.113+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=2716 volume=100 Jun 01 15:47:44 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:44.114+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 90ms Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::play index undefined Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo Jun 01 15:47:44 volumio volumio[1184]: info: [MER] 🔌 Starting SSE for Klassik Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand stop took 3 milliseconds Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:44 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand clear took 3 milliseconds Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo" Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:44 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:44 volumio volumio[1184]: error: updateQueue error: null Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:44 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 9ms Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth_klassik/motherearth.klassik.flac-lo" took 7 milliseconds Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 6ms Jun 01 15:47:44 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play Jun 01 15:47:44 volumio volumio[1184]: info: Jun 01 15:47:44 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Jun 01 15:47:44 volumio volumio[1184]: info: Ignoring MPD Status Update Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 28ms Jun 01 15:47:44 volumio volumio[1184]: info: sendMpdCommand play took 6 milliseconds Jun 01 15:47:44 volumio volumio[1184]: info: ------------------------------ 5ms Jun 01 15:47:44 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:44 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:44 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Klassik · FLAC 96kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/klassik/flac96","streaming":true,"disableUiControls":true,"samplerate":"96 kHz","bitdepth":"24 bit","duration":0,"seek":0} Jun 01 15:47:44 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Jun 01 15:47:44 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:47:45 volumio volumio[1184]: info: Jun 01 15:47:45 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:45 volumio volumio[1184]: info: Jun 01 15:47:45 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:45 volumio volumio[1184]: info: Jun 01 15:47:45 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:45 volumio volumio[1184]: info: Jun 01 15:47:45 volumio volumio[1184]: ---------------------------- MPD announces state update: player Jun 01 15:47:45 volumio volumio[1184]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand status took 31 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::getState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Jun 01 15:47:45 volumio volumio[1184]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand status took 25 milliseconds Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:45 volumio volumio[1184]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand status took 56 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand clearerror took 37 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand status took 37 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand clearerror took 35 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseState Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:45 volumio volumio[1184]: info: ------------------------------ 77ms Jun 01 15:47:45 volumio volumio[1184]: info: ------------------------------ 69ms Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand clearerror took 18 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 01 15:47:45 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jun 01 15:47:45 volumio volumio[1184]: info: ------------------------------ 80ms Jun 01 15:47:45 volumio volumio[1184]: info: ------------------------------ 78ms Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::volumioNext Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::next Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::stop Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::serviceStop Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::serviceStop Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} Jun 01 15:47:45 volumio volumio[1184]: verbose: CURRENT POSITION 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:45 volumio volumio[1184]: info: No code Jun 01 15:47:45 volumio volumio[1184]: info: CoreStateMachine::pushState Jun 01 15:47:45 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 01 15:47:45 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 01 15:47:45 volumio volumio[1184]: info: ControllerMpd::stop Jun 01 15:47:45 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.405+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.406+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.411+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.412+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.415+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.415+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.421+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 15:47:45 volumio volumio5-onboarding[1715]: time=2026-06-01T15:47:45.422+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.35:52712,00:00:00:00:00:00%03 @ 0x3432000" id=motherearthradio/klassik/flac96 title=Klassik Jun 01 15:47:45 volumio volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 15:47:45 volumio volumio[1184]: Error: socket hang up Jun 01 15:47:45 volumio volumio[1184]: at connResetException (node:internal/errors:720:14) Jun 01 15:47:45 volumio volumio[1184]: at TLSSocket.socketCloseListener (node:_http_client:468:25) Jun 01 15:47:45 volumio volumio[1184]: at TLSSocket.emit (node:events:526:35) Jun 01 15:47:45 volumio volumio[1184]: at node:net:337:12 Jun 01 15:47:45 volumio volumio[1184]: at TCP.done (node:_tls_wrap:631:7) { Jun 01 15:47:45 volumio volumio[1184]: code: 'ECONNRESET' Jun 01 15:47:45 volumio volumio[1184]: } Jun 01 15:47:45 volumio volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 15:47:46 volumio sudo[3181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 15:46' Jun 01 15:47:46 volumio sudo[3181]: 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"