-- Logs begin at Wed 2026-08-19 11:13:58 CEST, end at Sun 2026-08-30 13:45:43 CEST. -- Aug 30 13:44:40 rivo volumio[3288]: info: Aug 30 13:44:40 rivo volumio[3288]: ---------------------------- MPD announces system playlist update Aug 30 13:44:40 rivo volumio[3288]: info: Ignoring MPD Status Update Aug 30 13:44:40 rivo volumio[3288]: info: Aug 30 13:44:40 rivo volumio[3288]: ---------------------------- MPD announces state update: player Aug 30 13:44:40 rivo volumio[3288]: info: ControllerMpd::getState Aug 30 13:44:40 rivo volumio[3288]: verbose: ControllerMpd::sendMpdCommand status Aug 30 13:44:40 rivo volumio[3288]: info: ------------------------------ 5ms Aug 30 13:44:40 rivo volumio[3288]: info: sendMpdCommand status took 4 milliseconds Aug 30 13:44:40 rivo volumio[3288]: verbose: ControllerMpd::parseState Aug 30 13:44:40 rivo volumio[3288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 13:44:40 rivo volumio[3288]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 13:44:40 rivo volumio[3288]: verbose: ControllerMpd::parseTrackInfo Aug 30 13:44:40 rivo volumio[3288]: info: ControllerMpd::pushState Aug 30 13:44:40 rivo volumio[3288]: info: CoreCommandRouter::servicePushState Aug 30 13:44:40 rivo volumio[3288]: info: CorePlayQueue::getTrack 0 Aug 30 13:44:40 rivo volumio[3288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10464112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO Soul & Jazz - Non-stop - NPO","artist":"NPO Soul&Jazz","album":null,"uri":"http://icecast.omroep.nl/radio6-bb-mp3","trackType":""} Aug 30 13:44:40 rivo volumio[3288]: verbose: CURRENT POSITION 0 Aug 30 13:44:40 rivo volumio[3288]: info: CoreStateMachine::syncState stateService play Aug 30 13:44:40 rivo volumio[3288]: info: CoreStateMachine::syncState currentStatus play Aug 30 13:44:40 rivo volumio[3288]: info: Received an update from plugin. extracting info from payload Aug 30 13:44:40 rivo volumio[3288]: info: CoreStateMachine::pushState Aug 30 13:44:40 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:44:40 rivo volumio[3288]: info: CoreCommandRouter::volumioPushState Aug 30 13:44:40 rivo volumio[3288]: info: CoreStateMachine::pushState Aug 30 13:44:40 rivo volumio[3288]: info: CoreCommandRouter::volumioPushState Aug 30 13:44:40 rivo volumio[3288]: info: ------------------------------ 19ms Aug 30 13:44:40 rivo volumio[3288]: info: Signalling Playback active due to playback status change Aug 30 13:44:40 rivo volumio[3288]: info: Signalling Playback active due to playback status change Aug 30 13:44:42 rivo volumio[3288]: info: Aug 30 13:44:42 rivo volumio[3288]: ---------------------------- MPD announces system playlist update Aug 30 13:44:42 rivo volumio[3288]: info: Ignoring MPD Status Update Aug 30 13:44:42 rivo volumio[3288]: info: Aug 30 13:44:42 rivo volumio[3288]: ---------------------------- MPD announces state update: player Aug 30 13:44:42 rivo volumio[3288]: info: ControllerMpd::getState Aug 30 13:44:42 rivo volumio[3288]: verbose: ControllerMpd::sendMpdCommand status Aug 30 13:44:42 rivo volumio[3288]: info: ------------------------------ 5ms Aug 30 13:44:42 rivo volumio[3288]: info: sendMpdCommand status took 3 milliseconds Aug 30 13:44:42 rivo volumio[3288]: verbose: ControllerMpd::parseState Aug 30 13:44:42 rivo volumio[3288]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 13:44:42 rivo volumio[3288]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 13:44:42 rivo volumio[3288]: verbose: ControllerMpd::parseTrackInfo Aug 30 13:44:42 rivo volumio[3288]: info: ControllerMpd::pushState Aug 30 13:44:42 rivo volumio[3288]: info: CoreCommandRouter::servicePushState Aug 30 13:44:42 rivo volumio[3288]: info: CorePlayQueue::getTrack 0 Aug 30 13:44:42 rivo volumio[3288]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10466112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Montell Jordan - Get It On Tonite","artist":"NPO Soul&Jazz","album":null,"uri":"http://icecast.omroep.nl/radio6-bb-mp3","trackType":""} Aug 30 13:44:42 rivo volumio[3288]: verbose: CURRENT POSITION 0 Aug 30 13:44:42 rivo volumio[3288]: info: CoreStateMachine::syncState stateService play Aug 30 13:44:42 rivo volumio[3288]: info: CoreStateMachine::syncState currentStatus play Aug 30 13:44:42 rivo volumio[3288]: info: Received an update from plugin. extracting info from payload Aug 30 13:44:42 rivo volumio[3288]: info: CoreStateMachine::pushState Aug 30 13:44:42 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:44:42 rivo volumio[3288]: info: CoreCommandRouter::volumioPushState Aug 30 13:44:42 rivo volumio[3288]: info: CoreStateMachine::pushState Aug 30 13:44:42 rivo volumio[3288]: info: CoreCommandRouter::volumioPushState Aug 30 13:44:42 rivo volumio[3288]: info: ------------------------------ 18ms Aug 30 13:44:42 rivo volumio[3288]: info: Signalling Playback active due to playback status change Aug 30 13:44:42 rivo volumio[3288]: info: Signalling Playback active due to playback status change Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.146+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.13:54828 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.182+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.021404354s platform=PLATFORM_ANDROID version=6.260807.0 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.183+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02177589s timeout=20s Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.183+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" Aug 30 13:45:10 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:10 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.189+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" name=Rivo Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.190+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" language=en Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.193+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" timezone=Europe/Amsterdam Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.196+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" available=true connected=false macAddress= ip4Address= ip6Address= Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.201+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" available=true connected=true macAddress=40:d9:5a:6f:43:26 ip4Address=192.168.2.16/24 ip6Address= ssid=H369A90A01E Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.201+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" setupComplete=true Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 13:45:10 rivo volumio[3288]: amixer -c 5 info | grep "EXOGAL 06.51e DoP" Aug 30 13:45:10 rivo volumio[3288]: Card hw:5 'DoP'/'XMOS EXOGAL 06.51e DoP at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 30 13:45:10 rivo volumio[3288]: amixer -c 5 info | grep "EXOGAL 06.51e DoP" Aug 30 13:45:10 rivo volumio[3288]: Card hw:5 'DoP'/'XMOS EXOGAL 06.51e DoP at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.612+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" selectedOutputId=5 Aug 30 13:45:10 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:10 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.629+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" currentVersion=3.912 latestVersion=3.912 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.630+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" status=UPDATE_STATUS_NONE progress=0 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.630+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" userId=XfRwkjKHjkQZzdvPKcJGX4P19gc2 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.631+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" providers=9 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.632+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" plugins=32 Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.637+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" state=STATUS_PLAYING positionMs=10494490 volume=100 Aug 30 13:45:10 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:10.638+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.13:54828 @ 0x1dd8840" id=http://icecast.omroep.nl/radio6-bb-mp3 title="Montell Jordan - Get It On Tonite" Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:10 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:10 rivo volumio[3288]: verbose: New Socket.io Connection to 192.168.2.16:3000 from 192.168.2.13 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 13:45:10 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.438+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.020303499s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.459+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.564+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=http://pushupdates.volumio.org duration=100.51906ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.719+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=245.500295ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.739+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://securetoken.googleapis.com duration=277.243205ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.790+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://www.googleapis.com duration=329.309812ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.830+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://google.com duration=369.788288ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.876+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=413.317413ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.877+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=412.693534ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.917+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=453.470637ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.930+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://functions.volumio.cloud duration=468.271ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.932+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://functions.volumio.cloud duration=470.971688ms Aug 30 13:45:11 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:11.944+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=http://cddb.volumio.org duration=481.334933ms Aug 30 13:45:12 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:12.006+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=http://plugins.volumio.org duration=542.820654ms Aug 30 13:45:12 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:12.061+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.2.13:54828 @ 0x1dd8840" latency=-1.02257454s timeout=10s endpoint=https://database.volumio.cloud duration=596.745775ms Aug 30 13:45:12 rivo sudo[11384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:45:12 rivo sudo[11384]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:45:12 rivo sudo[11384]: pam_unix(sudo:session): session closed for user root Aug 30 13:45:12 rivo sudo[11386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:45:12 rivo sudo[11386]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:45:12 rivo sudo[11386]: pam_unix(sudo:session): session closed for user root Aug 30 13:45:12 rivo volumio[3288]: verbose: New Socket.io Connection to 192.168.2.16 from 192.168.2.13 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7a Build/CP2A.260705.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 13:45:12 rivo sudo[11392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:45:12 rivo sudo[11392]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:45:12 rivo sudo[11392]: pam_unix(sudo:session): session closed for user root Aug 30 13:45:12 rivo sudo[11395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:45:12 rivo sudo[11395]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:45:12 rivo sudo[11395]: pam_unix(sudo:session): session closed for user root Aug 30 13:45:12 rivo volumio[3288]: verbose: New Socket.io Connection to 192.168.2.16 from 192.168.2.13 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7a Build/CP2A.260705.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::volumioGetQueue Aug 30 13:45:12 rivo volumio[3288]: info: CoreStateMachine::getQueue Aug 30 13:45:12 rivo volumio[3288]: info: CorePlayQueue::getQueue Aug 30 13:45:12 rivo volumio[3288]: info: Listing playlists Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 13:45:12 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:12 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:45:12 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:45:13 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:45:14 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:14 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:14 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:15 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:45:15 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:15 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:15 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:15 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:15 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:15 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:18 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 13:45:18 rivo volumio[3288]: info: Preload queue cleared Aug 30 13:45:19 rivo volumio5-onboarding[3892]: time=2026-08-30T13:45:19.225+02:00 level=INFO msg="new address was allocated" component=ble/conn old=14 new=15 Aug 30 13:45:19 rivo dbus-daemon[2840]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.15" (uid=0 pid=3892 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3207 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Aug 30 13:45:22 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 13:45:25 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 30 13:45:26 rivo volumio[3288]: info: Preload queue cleared Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:45:30 rivo volumio[3288]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 13:45:30 rivo volumio[3288]: info: Received Get System Version Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 13:45:30 rivo volumio[3288]: info: Received Get System Info Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:45:30 rivo volumio[3288]: info: Discovery: Getting this device information Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:30 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:45:42 rivo volumio[3288]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Aug 30 13:45:42 rivo volumio[3288]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:45:42 rivo volumio[3288]: TypeError: Cannot read property 'then' of undefined Aug 30 13:45:42 rivo volumio[3288]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Aug 30 13:45:42 rivo volumio[3288]: at Socket.emit (events.js:400:28) Aug 30 13:45:42 rivo volumio[3288]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 13:45:42 rivo volumio[3288]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 30 13:45:42 rivo volumio[3288]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:45:43 rivo sudo[11501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 13:44 Aug 30 13:45:43 rivo sudo[11501]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:38:48 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8e381701610c2a79deb52e712150c089"