-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Fri 2026-04-10 20:12:45 JST. -- Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.598+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.104.226:55141 Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.679+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.104.226:55141 @ 0x30dac60" latency=48.564598ms platform=PLATFORM_IOS version=5.260331.0 Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.681+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.104.226:55141 @ 0x30dac60" latency=50.439607ms timeout=10s Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.681+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 10 20:11:17 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:17 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:17 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.691+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" name=Rivoplus Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.695+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" language=en Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.699+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" timezone=Asia/Tokyo Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.701+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" available=true connected=true macAddress=02:00:00:22:24:01 ip4Address=192.168.104.230/24 ip6Address= Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.704+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.704+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" setupComplete=true Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 10 20:11:17 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:17 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 20:11:17 rivoplus volumio[3179]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Apr 10 20:11:17 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:17 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:17 rivoplus volumio[3179]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Apr 10 20:11:17 rivoplus volumio[3179]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Apr 10 20:11:17 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:17 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:17 rivoplus volumio[3179]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.926+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" selectedOutputId=5 Apr 10 20:11:17 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:17 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:17 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.954+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" currentVersion=3.912 latestVersion=3.912 Apr 10 20:11:17 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:17.955+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.104.226:55141 @ 0x30dac60" status=UPDATE_STATUS_NONE progress=0 Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:17 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:17 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:17 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 10 20:11:18 rivoplus volumio[3179]: verbose: New Socket.io Connection to 192.168.104.230:3000 from 192.168.104.226 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Apr 10 20:11:18 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 10 20:11:18 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 10 20:11:19 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:19.285+09:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=H5beuOhbXobYN7MJKKFdsBNOuRA3 tokenExpiry=2026-04-10T21:11:19.285+09:00 Apr 10 20:11:20 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:20.568+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" userId=H5beuOhbXobYN7MJKKFdsBNOuRA3 Apr 10 20:11:20 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:20 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:20 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.515+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.104.226:55141 @ 0x30dac60" latency=48.02561ms timeout=10s Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.515+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.104.226:55141 @ 0x30dac60" latency=48.907151ms platform=PLATFORM_IOS version=5.260331.0 Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.515+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" Apr 10 20:11:21 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:21 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:21 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.522+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" name=Rivoplus Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.524+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" language=en Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.528+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" timezone=Asia/Tokyo Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.529+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" available=true connected=true macAddress=02:00:00:22:24:01 ip4Address=192.168.104.230/24 ip6Address= Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.532+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.532+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" setupComplete=true Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 10 20:11:21 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:21 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 20:11:21 rivoplus volumio[3179]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Apr 10 20:11:21 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:21 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:21 rivoplus volumio[3179]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Apr 10 20:11:21 rivoplus volumio[3179]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Apr 10 20:11:21 rivoplus volumio[3179]: No protocol specified Apr 10 20:11:21 rivoplus volumio[3179]: xcb_connection_has_error() returned true Apr 10 20:11:21 rivoplus volumio[3179]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.743+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" selectedOutputId=5 Apr 10 20:11:21 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:21 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:21 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:21 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.756+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" currentVersion=3.912 latestVersion=3.912 Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.757+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.104.226:55141 @ 0x30dac60" status=UPDATE_STATUS_NONE progress=0 Apr 10 20:11:21 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:21.757+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" userId=H5beuOhbXobYN7MJKKFdsBNOuRA3 Apr 10 20:11:22 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:22.532+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Apr 10 20:11:22 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:22.533+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" providers=9 Apr 10 20:11:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:22 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.845+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" plugins=26 Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.846+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" plugins=26 Apr 10 20:11:23 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:23 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:23 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:23 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.855+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" state=STATUS_STOPPED positionMs=0 volume=100 Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.855+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" state=STATUS_STOPPED positionMs=0 volume=100 Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.856+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" id= title= Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.857+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" id= title= Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.858+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" state=STATUS_STOPPED positionMs=0 volume=100 Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.858+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.226:55141 @ 0x30dac60" id= title= Apr 10 20:11:23 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:23.874+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.104.226:55141 @ 0x30dac60" latency=48.207836ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 10 20:11:24 rivoplus sudo[9863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 20:11:24 rivoplus sudo[9863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 20:11:24 rivoplus sudo[9863]: pam_unix(sudo:session): session closed for user root Apr 10 20:11:24 rivoplus sudo[9866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 20:11:24 rivoplus sudo[9866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 20:11:24 rivoplus sudo[9866]: pam_unix(sudo:session): session closed for user root Apr 10 20:11:24 rivoplus volumio[3179]: verbose: New Socket.io Connection to 192.168.104.230 from 192.168.104.226 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: 8 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 10 20:11:24 rivoplus volumio[3179]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 10 20:11:24 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:24 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: Listing playlists Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 20:11:24 rivoplus sudo[9871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 20:11:24 rivoplus sudo[9871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 10 20:11:24 rivoplus sudo[9871]: pam_unix(sudo:session): session closed for user root Apr 10 20:11:24 rivoplus sudo[9873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 20:11:24 rivoplus sudo[9873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 20:11:24 rivoplus sudo[9873]: pam_unix(sudo:session): session closed for user root Apr 10 20:11:24 rivoplus volumio[3179]: verbose: New Socket.io Connection to 192.168.104.230 from 192.168.104.226 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: 8 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 10 20:11:24 rivoplus volumio[3179]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 10 20:11:24 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:24 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:24 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:24 rivoplus volumio[3179]: info: Listing playlists Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 20:11:24 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 10 20:11:25 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:25 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:26 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:26 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:26 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:26 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:27 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:27.458+09:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Apr 10 20:11:27 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:28 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:28 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 20:11:28 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:28 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:28 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:28 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:28 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:28 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:28 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:32 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:32.611+09:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Apr 10 20:11:32 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:33 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:34 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 10 20:11:36 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:36.593+09:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Apr 10 20:11:36 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:37 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:40 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:40.763+09:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Apr 10 20:11:41 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:41 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:41 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetQueue Apr 10 20:11:41 rivoplus volumio[3179]: info: CoreStateMachine::getQueue Apr 10 20:11:41 rivoplus volumio[3179]: info: CorePlayQueue::getQueue Apr 10 20:11:41 rivoplus volumio[3179]: info: Executing endpoint metavolumio Apr 10 20:11:41 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 20:11:41 rivoplus volumio[3179]: info: Executing endpoint metavolumio Apr 10 20:11:41 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 20:11:43 rivoplus volumio[3179]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 10 20:11:43 rivoplus volumio[3179]: info: Received Get System Version Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 20:11:43 rivoplus volumio[3179]: info: Received Get System Info Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 20:11:43 rivoplus volumio[3179]: info: Discovery: Getting this device information Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:11:43 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:11:43 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 20:11:46 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:46.771+09:00 level=INFO msg="new address was allocated" component=ble/conn old=5 new=6 Apr 10 20:11:47 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:47 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:50 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:50.821+09:00 level=INFO msg="new address was allocated" component=ble/conn old=6 new=7 Apr 10 20:11:51 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:51 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:54 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:54.691+09:00 level=INFO msg="new address was allocated" component=ble/conn old=7 new=8 Apr 10 20:11:54 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:55 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:58 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:11:58.921+09:00 level=INFO msg="new address was allocated" component=ble/conn old=8 new=9 Apr 10 20:11:59 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:11:59 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:04 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:04.194+09:00 level=INFO msg="new address was allocated" component=ble/conn old=9 new=10 Apr 10 20:12:04 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:04 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:10 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:10.142+09:00 level=INFO msg="new address was allocated" component=ble/conn old=10 new=11 Apr 10 20:12:10 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:10 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:14 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:14.820+09:00 level=INFO msg="new address was allocated" component=ble/conn old=11 new=12 Apr 10 20:12:15 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:15 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:15 rivoplus volumio[3179]: info: CoreCommandRouter::volumioGetState Apr 10 20:12:15 rivoplus volumio[3179]: info: CorePlayQueue::getTrack 0 Apr 10 20:12:20 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:20.326+09:00 level=INFO msg="new address was allocated" component=ble/conn old=12 new=13 Apr 10 20:12:20 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:20 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:25 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:25.292+09:00 level=INFO msg="new address was allocated" component=ble/conn old=13 new=14 Apr 10 20:12:25 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:25 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:31 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:31.606+09:00 level=INFO msg="new address was allocated" component=ble/conn old=14 new=15 Apr 10 20:12:31 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:32 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:33 rivoplus volumio[3179]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 10 20:12:34 rivoplus volumio[3179]: info: Preload queue cleared Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281419 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279883 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281931 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286283 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303947 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285259 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298571 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296523 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986827 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283723 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287051 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300875 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279627 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284491 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285771 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282699 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296011 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284747 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3290379 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287819 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281675 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286795 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281163 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302923 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280139 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3290123 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282187 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301387 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279371 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303179 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295243 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285515 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301899 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3294731 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297803 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283979 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279115 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304715 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280395 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287563 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303691 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984779 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298315 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297291 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295755 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289611 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302155 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300619 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300107 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286539 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304459 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5983755 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287307 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280907 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297035 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986571 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984267 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299851 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985035 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288075 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301131 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289099 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5987083 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285003 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986059 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985803 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299339 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298827 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299083 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280651 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304971 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986315 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298059 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282443 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288331 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5983499 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283467 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296779 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286027 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304203 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299595 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302667 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300363 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296267 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984011 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3305227 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295499 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289355 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297547 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288843 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985291 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284235 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301643 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3294475 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985547 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289867 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302411 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288587 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283211 Apr 10 20:12:34 rivoplus volumio[3179]: info: Preloading song: upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303435 Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281419 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279883 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281931 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286283 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303947 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285259 in service upnp_browser Apr 10 20:12:34 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298571 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296523 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986827 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283723 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287051 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300875 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279627 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284491 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285771 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282699 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296011 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284747 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3290379 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287819 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281675 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286795 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3281163 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302923 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280139 in service upnp_browser Apr 10 20:12:35 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3290123 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282187 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301387 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279371 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303179 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295243 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285515 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301899 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3294731 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297803 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283979 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3279115 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304715 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280395 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287563 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303691 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984779 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298315 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297291 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295755 in service upnp_browser Apr 10 20:12:36 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289611 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302155 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300619 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300107 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286539 in service upnp_browser Apr 10 20:12:37 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:37.201+09:00 level=INFO msg="new address was allocated" component=ble/conn old=15 new=16 Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304459 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5983755 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3287307 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280907 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297035 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986571 in service upnp_browser Apr 10 20:12:37 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984267 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299851 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985035 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288075 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301131 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289099 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5987083 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3285003 in service upnp_browser Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986059 in service upnp_browser Apr 10 20:12:37 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:37 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985803 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299339 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298827 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299083 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3280651 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304971 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5986315 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3298059 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3282443 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288331 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5983499 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283467 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296779 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3286027 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3304203 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3299595 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302667 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3300363 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3296267 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5984011 in service upnp_browser Apr 10 20:12:38 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3305227 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3295499 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289355 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3297547 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288843 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985291 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3284235 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3301643 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3294475 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R5985547 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3289867 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3302411 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3288587 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3283211 in service upnp_browser Apr 10 20:12:39 rivoplus volumio[3179]: info: Exploding uri upnp/http://192.168.104.134:9000/dev0/srv1/control@0$1$18$229$18893$18894R3303435 in service upnp_browser Apr 10 20:12:41 rivoplus volumio5-onboarding[3719]: time=2026-04-10T20:12:41.371+09:00 level=INFO msg="new address was allocated" component=ble/conn old=16 new=17 Apr 10 20:12:41 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:42 rivoplus dbus-daemon[2839]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3719 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=3309 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Apr 10 20:12:43 rivoplus volumio[3179]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Best%20Audiophile%20Voices/729dbc6e-8d3f-4420-acdf-2c1a4bb950f9.jpg' Apr 10 20:12:43 rivoplus volumio[3179]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carol%20Kidd/0968e706-8bf5-422e-87ed-bcb1d7ed602c.jpg' Apr 10 20:12:44 rivoplus volumio[3179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:12:44 rivoplus volumio[3179]: Error: connect ECONNREFUSED 158.69.210.98:443 Apr 10 20:12:44 rivoplus volumio[3179]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 10 20:12:44 rivoplus volumio[3179]: errno: -111, Apr 10 20:12:44 rivoplus volumio[3179]: code: 'ECONNREFUSED', Apr 10 20:12:44 rivoplus volumio[3179]: syscall: 'connect', Apr 10 20:12:44 rivoplus volumio[3179]: address: '158.69.210.98', Apr 10 20:12:44 rivoplus volumio[3179]: port: 443 Apr 10 20:12:44 rivoplus volumio[3179]: } Apr 10 20:12:44 rivoplus volumio[3179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:12:45 rivoplus sudo[10084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-10 20:11 Apr 10 20:12:45 rivoplus sudo[10084]: 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"