-- Logs begin at Sun 2026-08-16 12:20:55 CEST, end at Mon 2026-08-31 17:23:46 CEST. -- Aug 31 17:22:03 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:03 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:08 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:08 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:13 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:13 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:18 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:18 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:24 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:24 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:29 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:29 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:34 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:34 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:39 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:39 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.425+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.44:64585 Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.453+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.44:64585 @ 0x2430630" latency=12.290717ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.454+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.44:64585 @ 0x2430630" latency=13.945266ms timeout=20s Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.454+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 17:22:41 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:41 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:41 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.467+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" name=Rivo Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.471+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" language=it Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.477+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" timezone=Europe/Rome Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.479+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.483+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" available=true connected=true macAddress=b8:13:32:e5:d8:c6 ip4Address=192.168.1.186/24 ip6Address= ssid="Wind3 HUB-F062F5_5" Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.484+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" setupComplete=true Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 17:22:41 rivo volumio[3323]: amixer -c 5 info | grep "Volumio Preciso" Aug 31 17:22:41 rivo volumio[3323]: Card hw:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 31 17:22:41 rivo volumio[3323]: amixer -c 5 info | grep "Volumio Preciso" Aug 31 17:22:41 rivo volumio[3323]: Card hw:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.899+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" selectedOutputId=5 Aug 31 17:22:41 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:41 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:41 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.920+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" currentVersion=3.912 latestVersion=3.912 Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.920+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.44:64585 @ 0x2430630" status=UPDATE_STATUS_NONE progress=0 Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.921+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" userId= Aug 31 17:22:41 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:41.922+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" providers=3 Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:22:41 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.438+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.44:64585 @ 0x2430630" latency=24.924499ms timeout=20s Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.438+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.438+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.44:64585 @ 0x2430630" latency=26.608585ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 17:22:42 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:42 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:42 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.447+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" name=Rivo Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.448+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" language=it Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.454+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" timezone=Europe/Rome Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.456+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.461+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" available=true connected=true macAddress=b8:13:32:e5:d8:c6 ip4Address=192.168.1.186/24 ip6Address= ssid="Wind3 HUB-F062F5_5" Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.462+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" setupComplete=true Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 17:22:42 rivo volumio[3323]: amixer -c 5 info | grep "Volumio Preciso" Aug 31 17:22:42 rivo volumio[3323]: Card hw:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 31 17:22:42 rivo volumio[3323]: amixer -c 5 info | grep "Volumio Preciso" Aug 31 17:22:42 rivo volumio[3323]: Card hw:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.879+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" selectedOutputId=5 Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:42 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:42 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:42 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:42 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:42 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.917+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" currentVersion=3.912 latestVersion=3.912 Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.918+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.44:64585 @ 0x2430630" status=UPDATE_STATUS_NONE progress=0 Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.918+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" userId= Aug 31 17:22:42 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:42.919+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" providers=3 Aug 31 17:22:42 rivo volumio[3323]: verbose: New Socket.io Connection to 192.168.1.186:3000 from 192.168.1.44 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 17:22:42 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 17:22:44 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:44 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.260+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" plugins=32 Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.260+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" plugins=32 Aug 31 17:22:48 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:48 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:48 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:48 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.275+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.276+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.276+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" state=STATUS_STOPPED positionMs=0 volume=100 Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.276+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" id="mnt/USB/Crucial_X6/fa_musica_new_x6/Curtis Harding - If Words Were Flowers (2021) [24Bit-48kHz] FLAC/01. If Words Were Flowers.flac" title="If Words Were Flowers" Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.277+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" id="mnt/USB/Crucial_X6/fa_musica_new_x6/Curtis Harding - If Words Were Flowers (2021) [24Bit-48kHz] FLAC/01. If Words Were Flowers.flac" title="If Words Were Flowers" Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.279+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.44:64585 @ 0x2430630" id="mnt/USB/Crucial_X6/fa_musica_new_x6/Curtis Harding - If Words Were Flowers (2021) [24Bit-48kHz] FLAC/01. If Words Were Flowers.flac" title="If Words Were Flowers" Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.330+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.44:64585 @ 0x2430630" latency=8.701677ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.359+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s Aug 31 17:22:48 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:48.765+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Aug 31 17:22:49 rivo dbus-daemon[2855]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.13" (uid=0 pid=3636 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=3224 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Aug 31 17:22:49 rivo sudo[4673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 17:22:49 rivo sudo[4673]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 17:22:49 rivo sudo[4675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 17:22:49 rivo sudo[4673]: pam_unix(sudo:session): session closed for user root Aug 31 17:22:49 rivo sudo[4675]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 17:22:49 rivo sudo[4675]: pam_unix(sudo:session): session closed for user root Aug 31 17:22:49 rivo volumio[3323]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.44 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 17:22:49 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:49.335+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=975.972323ms Aug 31 17:22:49 rivo sudo[4681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 17:22:49 rivo sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 17:22:49 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:49.435+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://www.googleapis.com duration=1.072018873s Aug 31 17:22:49 rivo sudo[4681]: pam_unix(sudo:session): session closed for user root Aug 31 17:22:49 rivo sudo[4684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 17:22:49 rivo sudo[4684]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 17:22:49 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:49.450+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.088656614s Aug 31 17:22:49 rivo sudo[4684]: pam_unix(sudo:session): session closed for user root Aug 31 17:22:49 rivo volumio[3323]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.44 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:49 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::volumioGetQueue Aug 31 17:22:49 rivo volumio[3323]: info: CoreStateMachine::getQueue Aug 31 17:22:49 rivo volumio[3323]: info: CorePlayQueue::getQueue Aug 31 17:22:49 rivo volumio[3323]: info: Listing playlists Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 17:22:49 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:49 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:49 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:49 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 17:22:49 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 17:22:50 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:50.154+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://google.com duration=1.791189559s Aug 31 17:22:50 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 17:22:50 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:22:51 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:51 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:51 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:51 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:52 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:22:52 rivo volumio[3323]: info: Received Get System Info Aug 31 17:22:52 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:22:52 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:22:52 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:22:52 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:22:52 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:22:52 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:22:54 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:22:54 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:22:55 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:55.613+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=http://pushupdates.volumio.org duration=7.252687751s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.004+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=7.644631737s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.090+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://functions.volumio.cloud duration=7.730623076s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.091+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://functions.volumio.cloud duration=7.727782022s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.106+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=7.746052395s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.211+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://database.volumio.cloud duration=7.848516765s Aug 31 17:22:56 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:56.585+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=8.221408917s Aug 31 17:22:58 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:58.360+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=http://plugins.volumio.org duration=9.999288877s error="Head \"http://plugins.volumio.org\": context deadline exceeded" Aug 31 17:22:58 rivo volumio5-onboarding[3636]: time=2026-08-31T17:22:58.360+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.44:64585 @ 0x2430630" latency=11.111597ms timeout=10s endpoint=http://cddb.volumio.org duration=9.999857796s error="Head \"http://cddb.volumio.org\": context deadline exceeded" Aug 31 17:22:59 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 17:23:02 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 17:23:02 rivo volumio[3323]: info: CURURI: music-library Aug 31 17:23:02 rivo volumio[3323]: info: Preload queue cleared Aug 31 17:23:04 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:23:04 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:23:04 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 17:23:04 rivo volumio[3323]: info: CURURI: music-library/USB Aug 31 17:23:04 rivo volumio[3323]: info: Preload queue cleared Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 17:23:07 rivo volumio[3323]: info: CURURI: music-library/USB/Crucial_X6 Aug 31 17:23:07 rivo volumio[3323]: info: Preload queue cleared Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 17:23:07 rivo volumio[3323]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 17:23:07 rivo volumio[3323]: info: Received Get System Version Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 17:23:07 rivo volumio[3323]: info: Received Get System Info Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:23:07 rivo volumio[3323]: info: Discovery: Getting this device information Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::volumioGetState Aug 31 17:23:07 rivo volumio[3323]: info: CorePlayQueue::getTrack 0 Aug 31 17:23:07 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:23:14 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:23:14 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:23:24 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:23:24 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:23:25 rivo volumio[3323]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 17:23:25 rivo volumio[3323]: info: CURURI: artists:// Aug 31 17:23:25 rivo volumio[3323]: info: Preload queue cleared Aug 31 17:23:34 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:23:34 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:23:41 rivo volumio[3323]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 31 17:23:41 rivo volumio[3323]: info: Discovery: Restarting Browsing Aug 31 17:23:46 rivo volumio[3323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:23:46 rivo volumio[3323]: TypeError: Cannot read property 'slice' of null Aug 31 17:23:46 rivo volumio[3323]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 31 17:23:46 rivo volumio[3323]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 31 17:23:46 rivo volumio[3323]: at Object.onceWrapper (events.js:520:26) Aug 31 17:23:46 rivo volumio[3323]: at ClientRequest.emit (events.js:400:28) Aug 31 17:23:46 rivo volumio[3323]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Aug 31 17:23:46 rivo volumio[3323]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Aug 31 17:23:46 rivo volumio[3323]: at TLSSocket.socketOnData (_http_client.js:515:22) Aug 31 17:23:46 rivo volumio[3323]: at TLSSocket.emit (events.js:400:28) Aug 31 17:23:46 rivo volumio[3323]: at addChunk (internal/streams/readable.js:293:12) Aug 31 17:23:46 rivo volumio[3323]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 31 17:23:46 rivo volumio[3323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:23:46 rivo sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 17:22 Aug 31 17:23:46 rivo sudo[4854]: 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"