-- Logs begin at Mon 2026-03-16 18:56:24 CET, end at Sat 2026-03-28 11:35:54 CET. -- Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.096+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.74:49202 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.186+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.74:49202 @ 0x286f470" latency=-1.570968469s timeout=10s Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.186+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.186+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.74:49202 @ 0x286f470" latency=-1.570108719s platform=PLATFORM_IOS version=5.260209.0 Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 11:35:26 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:26 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:26 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.206+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" name=Rivoplus Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.211+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" language=de Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.218+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" timezone=Europe/Berlin Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.220+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" available=true connected=true macAddress=02:00:00:2d:18:01 ip4Address=192.168.178.114/24 ip6Address= Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.223+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.225+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" setupComplete=true Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 11:35:26 rivoplus volumio[3115]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Mar 28 11:35:26 rivoplus volumio[3115]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Mar 28 11:35:26 rivoplus volumio[3115]: amixer -c 1 info | grep "ADI-2 DAC (52071389)" Mar 28 11:35:26 rivoplus volumio[3115]: Card hw:1 'DAC52071389'/'RME ADI-2 DAC (52071389) at usb-xhci-hcd.0.auto-1.4.2, high speed' Mar 28 11:35:26 rivoplus volumio[3115]: amixer -c 1 info | grep "ADI-2 DAC (52071389)" Mar 28 11:35:26 rivoplus volumio[3115]: Card hw:1 'DAC52071389'/'RME ADI-2 DAC (52071389) at usb-xhci-hcd.0.auto-1.4.2, high speed' Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.819+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" selectedOutputId=1 Mar 28 11:35:26 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:26 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:26 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.856+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" currentVersion=3.912 latestVersion=3.912 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.857+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.74:49202 @ 0x286f470" status=UPDATE_STATUS_NONE progress=0 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.858+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" userId=oBVzfhBjRrNdTyWIwMUxo6B2rqE2 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.858+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" providers=9 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.859+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" plugins=26 Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:26 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:26 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:26 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:26 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.889+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:35:26 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:26.889+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.74:49202 @ 0x286f470" id=qobuz://song/4849189 title=Once Mar 28 11:35:26 rivoplus volumio[3115]: verbose: New Socket.io Connection to 192.168.178.114:3000 from 192.168.178.74 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 28 11:35:27 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 11:35:27 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 11:35:27 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:27.983+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.74:49202 @ 0x286f470" latency=-1.581484427s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 28 11:35:29 rivoplus sudo[4485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 11:35:29 rivoplus sudo[4485]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 11:35:29 rivoplus sudo[4486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 11:35:29 rivoplus sudo[4486]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 11:35:29 rivoplus sudo[4486]: pam_unix(sudo:session): session closed for user root Mar 28 11:35:29 rivoplus sudo[4485]: pam_unix(sudo:session): session closed for user root Mar 28 11:35:29 rivoplus volumio[3115]: verbose: New Socket.io Connection to 192.168.178.114 from 192.168.178.74 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 28 11:35:29 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 11:35:29 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 11:35:30 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:30.055+01:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 28 11:35:30 rivoplus sudo[4493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 11:35:30 rivoplus sudo[4493]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 11:35:30 rivoplus sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 11:35:30 rivoplus sudo[4495]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 11:35:30 rivoplus sudo[4493]: pam_unix(sudo:session): session closed for user root Mar 28 11:35:30 rivoplus sudo[4495]: pam_unix(sudo:session): session closed for user root Mar 28 11:35:30 rivoplus volumio[3115]: verbose: New Socket.io Connection to 192.168.178.114 from 192.168.178.74 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:30 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 11:35:30 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:30 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:30 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:30 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:30 rivoplus volumio[3115]: info: Listing playlists Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 11:35:30 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:30 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 11:35:30 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:31 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:31 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:31 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 11:35:32 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 11:35:32 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:32 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:32 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:32 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:32 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:32 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:32 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:33 rivoplus volumio[3115]: info: Executing endpoint metavolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: Executing endpoint metavolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: Executing endpoint metavolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 11:35:33 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:33 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:33 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:33 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:34 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:34.435+01:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Mar 28 11:35:34 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:35 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:38 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetQueue Mar 28 11:35:38 rivoplus volumio[3115]: info: CoreStateMachine::getQueue Mar 28 11:35:38 rivoplus volumio[3115]: info: CorePlayQueue::getQueue Mar 28 11:35:40 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 11:35:40 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:40.915+01:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Mar 28 11:35:41 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:41 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:43 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 11:35:43 rivoplus volumio[3115]: info: CURURI: artists:// Mar 28 11:35:43 rivoplus volumio[3115]: info: Preload queue cleared Mar 28 11:35:44 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:44.395+01:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Mar 28 11:35:44 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:45 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:48 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:48.595+01:00 level=INFO msg="new address was allocated" component=ble/conn old=5 new=6 Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 11:35:48 rivoplus volumio[3115]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 11:35:48 rivoplus volumio[3115]: info: Received Get System Version Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 11:35:48 rivoplus volumio[3115]: info: Received Get System Info Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 11:35:48 rivoplus volumio[3115]: info: Discovery: Getting this device information Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::volumioGetState Mar 28 11:35:48 rivoplus volumio[3115]: info: CorePlayQueue::getTrack 0 Mar 28 11:35:48 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 11:35:48 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:49 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:50 rivoplus volumio[3115]: Searching plugin music_service/mpd Mar 28 11:35:50 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 28 11:35:51 rivoplus volumio[3115]: info: All search sources collected, pushing search results Mar 28 11:35:52 rivoplus volumio[3115]: Searching plugin music_service/mpd Mar 28 11:35:52 rivoplus volumio[3115]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 28 11:35:52 rivoplus volumio5-onboarding[4196]: time=2026-03-28T11:35:52.495+01:00 level=INFO msg="new address was allocated" component=ble/conn old=6 new=7 Mar 28 11:35:52 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:52 rivoplus volumio[3115]: info: All search sources collected, pushing search results Mar 28 11:35:53 rivoplus dbus-daemon[2853]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.21" (uid=0 pid=4196 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3366 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Mar 28 11:35:53 rivoplus volumio[3115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 11:35:53 rivoplus volumio[3115]: TypeError: Cannot read property 'slice' of null Mar 28 11:35:53 rivoplus volumio[3115]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 11:35:53 rivoplus volumio[3115]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 11:35:53 rivoplus volumio[3115]: at Object.onceWrapper (events.js:520:26) Mar 28 11:35:53 rivoplus volumio[3115]: at ClientRequest.emit (events.js:400:28) Mar 28 11:35:53 rivoplus volumio[3115]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 28 11:35:53 rivoplus volumio[3115]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 28 11:35:53 rivoplus volumio[3115]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 28 11:35:53 rivoplus volumio[3115]: at TLSSocket.emit (events.js:400:28) Mar 28 11:35:53 rivoplus volumio[3115]: at addChunk (internal/streams/readable.js:293:12) Mar 28 11:35:53 rivoplus volumio[3115]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 28 11:35:53 rivoplus volumio[3115]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 28 11:35:53 rivoplus volumio[3115]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 28 11:35:53 rivoplus volumio[3115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 11:35:54 rivoplus sudo[4586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 11:34 Mar 28 11:35:54 rivoplus sudo[4586]: 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="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"