-- Logs begin at Fri 2026-02-27 11:38:48 CET, end at Fri 2026-08-28 09:54:42 CEST. -- Aug 28 09:53:46 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:46.966+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.187:53666 Aug 28 09:53:46 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:46.988+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.187:45072 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.012+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.187:53666 @ 0x30012c0" latency=405.681099ms platform=PLATFORM_ANDROID version=6.260807.0 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.019+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.187:53666 @ 0x30012c0" latency=404.63394ms timeout=20s Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.019+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" Aug 28 09:53:47 rivo volumio[3477]: info: Received Get System Info Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:47 rivo volumio[3477]: info: Discovery: Getting this device information Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.026+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" name=Rivo Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.031+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" language=it Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.033+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.072213ms timeout=20s Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.033+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.035+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" timezone=Europe/Rome Aug 28 09:53:47 rivo volumio[3477]: info: Received Get System Info Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.038+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" available=true connected=true macAddress=02:00:00:05:0f:01 ip4Address=192.168.1.30/24 ip6Address= Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:47 rivo volumio[3477]: info: Discovery: Getting this device information Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.043+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" available=true connected=true macAddress=40:d9:5a:6f:92:5a ip4Address=192.168.1.217/24 ip6Address= ssid=TIM-49455649 Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.044+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" setupComplete=true Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.046+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" name=Rivo Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.047+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" language=it Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.170+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" timezone=Europe/Rome Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.172+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" available=true connected=true macAddress=02:00:00:05:0f:01 ip4Address=192.168.1.30/24 ip6Address= Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.179+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" available=true connected=true macAddress=40:d9:5a:6f:92:5a ip4Address=192.168.1.217/24 ip6Address= ssid=TIM-49455649 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.180+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" setupComplete=true Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: amixer -c 5 info | grep "AudioQuest DragonFly Cobalt v1." Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: Card hw:5 'v1'/'AudioQuest AudioQuest DragonFly Cobalt v1. at usb-xhci-hcd.0.auto-1.1, full spe' Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.451+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" selectedOutputId=0,2 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.451+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" selectedOutputId=0,2 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.462+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=10 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.463+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: amixer -c 5 info | grep "AudioQuest DragonFly Cobalt v1." Aug 28 09:53:47 rivo volumio[3477]: xcb_connection_has_error() returned true Aug 28 09:53:47 rivo volumio[3477]: Card hw:5 'v1'/'AudioQuest AudioQuest DragonFly Cobalt v1. at usb-xhci-hcd.0.auto-1.1, full spe' Aug 28 09:53:47 rivo volumio[3477]: info: Received Get System Info Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:47 rivo volumio[3477]: info: Discovery: Getting this device information Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.612+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" currentVersion=3.912 latestVersion=3.912 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.613+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.187:53666 @ 0x30012c0" status=UPDATE_STATUS_NONE progress=0 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.613+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" userId=phOViuWH61Oc9W5s9bUYr83uiB92 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.614+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" providers=9 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.615+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" plugins=32 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:53:47 rivo volumio[3477]: info: Discovery: Getting this device information Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:47 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.634+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=10 Aug 28 09:53:47 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:47.634+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666 @ 0x30012c0" id= title= Aug 28 09:53:47 rivo volumio[3477]: verbose: New Socket.io Connection to 192.168.1.217:3000 from 192.168.1.187 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 09:53:47 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.025+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.241573ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.101+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.217+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=http://pushupdates.volumio.org duration=114.182246ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.255+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=153.032862ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.396+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://www.googleapis.com duration=291.097199ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.452+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://securetoken.googleapis.com duration=349.373165ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.493+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=388.545365ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.496+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=394.123531ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.543+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=439.774279ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.606+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://google.com duration=501.429311ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.683+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://functions.volumio.cloud duration=580.939473ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.700+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://functions.volumio.cloud duration=595.519162ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.725+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=http://cddb.volumio.org duration=619.482128ms Aug 28 09:53:53 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:53.818+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=http://plugins.volumio.org duration=713.31206ms Aug 28 09:53:53 rivo sudo[9525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:53:53 rivo sudo[9525]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:53 rivo sudo[9525]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:53 rivo sudo[9528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:53:53 rivo sudo[9528]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:53 rivo sudo[9528]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:54 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:54.029+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.741376ms timeout=10s endpoint=https://database.volumio.cloud duration=924.828972ms Aug 28 09:53:54 rivo volumio[3477]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.187 UA: Mozilla/5.0 (Linux; Android 17; Pixel 10 Build/CP2A.260805.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 09:53:54 rivo volumio[3477]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:54 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 09:53:54 rivo sudo[9533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:53:54 rivo sudo[9533]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:54 rivo sudo[9533]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:54 rivo sudo[9537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:53:54 rivo sudo[9537]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:53:54 rivo sudo[9537]: pam_unix(sudo:session): session closed for user root Aug 28 09:53:54 rivo volumio[3477]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.187 UA: Mozilla/5.0 (Linux; Android 17; Pixel 10 Build/CP2A.260805.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:53:54 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 09:53:54 rivo volumio[3477]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 09:53:54 rivo volumio[3477]: info: Listing playlists Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 09:53:54 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.343+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.273815ms timeout=3s volume=11 Aug 28 09:53:59 rivo volumio[3477]: info: VolumeController::SetAlsaVolume11 Aug 28 09:53:59 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:53:59 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.396+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=11 Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.396+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:53:59 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.556+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.15032ms timeout=3s volume=12 Aug 28 09:53:59 rivo volumio[3477]: info: VolumeController::SetAlsaVolume12 Aug 28 09:53:59 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:53:59 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.605+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=12 Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.605+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:53:59 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.709+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.25614ms timeout=3s volume=13 Aug 28 09:53:59 rivo volumio[3477]: info: VolumeController::SetAlsaVolume13 Aug 28 09:53:59 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:53:59 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.758+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=13 Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.758+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:53:59 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.862+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.654463ms timeout=3s volume=14 Aug 28 09:53:59 rivo volumio[3477]: info: VolumeController::SetAlsaVolume14 Aug 28 09:53:59 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:53:59 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:53:59 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:53:59 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.911+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=14 Aug 28 09:53:59 rivo volumio5-onboarding[4275]: time=2026-08-28T09:53:59.911+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:53:59 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.017+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=408.183419ms timeout=3s volume=15 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume15 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.071+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=15 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.071+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.166+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.365211ms timeout=3s volume=16 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume16 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.215+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=16 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.216+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.318+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.629942ms timeout=3s volume=17 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume17 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.370+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=17 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.471+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.822756ms timeout=3s volume=18 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume18 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.528+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=18 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.529+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.621+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.620351ms timeout=3s volume=19 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume19 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.670+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=19 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.775+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.459177ms timeout=3s volume=20 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume20 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.828+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=20 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.828+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:00 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.926+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.238898ms timeout=3s volume=21 Aug 28 09:54:00 rivo volumio[3477]: info: VolumeController::SetAlsaVolume21 Aug 28 09:54:00 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:00 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:00 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:00 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.988+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=21 Aug 28 09:54:00 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:00.989+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.079+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.750513ms timeout=3s volume=22 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume22 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.128+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=22 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.129+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.232+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.095369ms timeout=3s volume=23 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume23 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.286+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=23 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.286+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.385+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.833645ms timeout=3s volume=24 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume24 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.432+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=24 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.433+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.587+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.364033ms timeout=3s volume=25 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume25 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.641+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=25 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.642+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.789+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.633211ms timeout=3s volume=26 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume26 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.837+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=26 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:01 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.941+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.614565ms timeout=3s volume=27 Aug 28 09:54:01 rivo volumio[3477]: info: VolumeController::SetAlsaVolume27 Aug 28 09:54:01 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:01 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:01 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:01 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.994+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=27 Aug 28 09:54:01 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:01.995+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.091+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.425778ms timeout=3s volume=28 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume28 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.141+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=28 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.141+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.245+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.460813ms timeout=3s volume=29 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume29 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.299+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=29 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.299+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.399+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.977595ms timeout=3s volume=30 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume30 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.450+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=30 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.554+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=407.87647ms timeout=3s volume=31 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume31 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.606+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=31 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.607+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.704+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.854934ms timeout=3s volume=32 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume32 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.772+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=32 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.772+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.855+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.546786ms timeout=3s volume=33 Aug 28 09:54:02 rivo volumio[3477]: info: VolumeController::SetAlsaVolume33 Aug 28 09:54:02 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:02 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:02 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.908+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=33 Aug 28 09:54:02 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:02.909+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:02 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.009+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.920942ms timeout=3s volume=34 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume34 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.057+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=34 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.058+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.158+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.595904ms timeout=3s volume=35 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume35 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.224+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=35 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.224+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.323+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=417.756645ms timeout=3s volume=36 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume36 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.371+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=36 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.462+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.855689ms timeout=3s volume=37 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume37 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.508+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=37 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.508+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.618+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.102241ms timeout=3s volume=38 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume38 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.664+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=38 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.665+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.824+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.393156ms timeout=3s volume=39 Aug 28 09:54:03 rivo volumio[3477]: info: VolumeController::SetAlsaVolume39 Aug 28 09:54:03 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:03 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:03 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:03 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.874+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=39 Aug 28 09:54:03 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:03.874+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:03 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.026+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.684625ms timeout=3s volume=40 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume40 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.080+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=40 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.080+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.229+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.085095ms timeout=3s volume=41 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume41 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.280+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=41 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.280+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.380+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.322735ms timeout=3s volume=42 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume42 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.435+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=42 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.436+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.530+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.658826ms timeout=3s volume=43 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume43 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.578+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=43 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.683+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.033308ms timeout=3s volume=44 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume44 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.729+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=44 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.730+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.837+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.472929ms timeout=3s volume=45 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume45 Aug 28 09:54:04 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:04 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:04 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:04 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.884+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=45 Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.885+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:04 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:04 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:04.987+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.854848ms timeout=3s volume=46 Aug 28 09:54:04 rivo volumio[3477]: info: VolumeController::SetAlsaVolume46 Aug 28 09:54:05 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:05 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=46 Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:05 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.142+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=404.243476ms timeout=3s volume=47 Aug 28 09:54:05 rivo volumio[3477]: info: VolumeController::SetAlsaVolume47 Aug 28 09:54:05 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:05 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.188+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=47 Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.189+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:05 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.345+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.812829ms timeout=3s volume=48 Aug 28 09:54:05 rivo volumio[3477]: info: VolumeController::SetAlsaVolume48 Aug 28 09:54:05 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:05 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.391+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=48 Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.391+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:05 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.496+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.204588ms timeout=3s volume=49 Aug 28 09:54:05 rivo volumio[3477]: info: VolumeController::SetAlsaVolume49 Aug 28 09:54:05 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:05 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.546+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=49 Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.546+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:05 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.647+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.268976ms timeout=3s volume=50 Aug 28 09:54:05 rivo volumio[3477]: info: VolumeController::SetAlsaVolume50 Aug 28 09:54:05 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:05 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:05 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:05 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.693+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=50 Aug 28 09:54:05 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:05.694+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:05 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:11 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:11.992+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=405.001703ms timeout=3s volume=49 Aug 28 09:54:11 rivo volumio[3477]: info: VolumeController::SetAlsaVolume49 Aug 28 09:54:12 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:12 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:12 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:12 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:12 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:12.037+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=49 Aug 28 09:54:12 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:12.038+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:12 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 09:54:12 rivo volumio[3477]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 09:54:12 rivo volumio[3477]: info: Received Get System Version Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 09:54:12 rivo volumio[3477]: info: Received Get System Info Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:54:12 rivo volumio[3477]: info: Discovery: Getting this device information Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:12 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:12 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:54:14 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:14.099+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=403.754416ms timeout=3s volume=0 Aug 28 09:54:14 rivo volumio[3477]: info: VolumeController::SetAlsaVolume0 Aug 28 09:54:14 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:14 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:14 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:14 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:14 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:14 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:14 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:14.145+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=0 Aug 28 09:54:14 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:14.146+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:14 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:15 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:15.946+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" latency=407.142355ms timeout=3s volume=49 Aug 28 09:54:15 rivo volumio[3477]: info: VolumeController::SetAlsaVolume49 Aug 28 09:54:15 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:15 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:15 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:15 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:15 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:15 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:15 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:15.995+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=49 Aug 28 09:54:15 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:15.995+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:16 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:33 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 28 09:54:33 rivo volumio[3477]: info: Playing Remote Device: http://192.168.1.30 Aug 28 09:54:33 rivo volumio[3477]: info: Aug 28 09:54:33 rivo volumio[3477]: ---------------------------- Client requests Volumio play Aug 28 09:54:33 rivo volumio[3477]: info: CoreCommandRouter::volumioPlay Aug 28 09:54:33 rivo volumio[3477]: info: CoreStateMachine::play index undefined Aug 28 09:54:33 rivo volumio[3477]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 09:54:33 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:36 rivo volumio[3477]: error: Cannot play Remote Device: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: Aug 28 09:54:36 rivo volumio[3477]: ---------------------------- Client requests Volume 56 Aug 28 09:54:36 rivo volumio[3477]: info: VolumeController::SetAlsaVolume56 Aug 28 09:54:36 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:36 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.681+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=56 Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.681+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:36 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:36 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: Aug 28 09:54:36 rivo volumio[3477]: ---------------------------- Client requests Volume 60 Aug 28 09:54:36 rivo volumio[3477]: info: VolumeController::SetAlsaVolume60 Aug 28 09:54:36 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:36 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.777+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.778+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:36 rivo volumio[3477]: info: Aug 28 09:54:36 rivo volumio[3477]: ---------------------------- Client requests Volume 61 Aug 28 09:54:36 rivo volumio[3477]: info: VolumeController::SetAlsaVolume61 Aug 28 09:54:36 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:36 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.841+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=61 Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:36 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:36 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:36 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:36 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:36 rivo volumio[3477]: info: Aug 28 09:54:36 rivo volumio[3477]: ---------------------------- Client requests Volume 62 Aug 28 09:54:36 rivo volumio[3477]: info: VolumeController::SetAlsaVolume62 Aug 28 09:54:36 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:36 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:36 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:36 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.989+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=62 Aug 28 09:54:36 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:36.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:36 rivo volumio[3477]: info: Aug 28 09:54:36 rivo volumio[3477]: ---------------------------- Client requests Volume 64 Aug 28 09:54:36 rivo volumio[3477]: info: VolumeController::SetAlsaVolume64 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.081+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=64 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.082+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 65 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume65 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.137+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=65 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.137+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 66 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume66 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.220+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=66 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.220+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 67 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume67 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.281+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=67 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.282+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 68 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume68 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.357+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=68 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.358+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 69 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume69 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.510+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=69 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.511+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 28 09:54:37 rivo volumio[3477]: info: Setting Remote Device Volume: http://192.168.1.30 Aug 28 09:54:37 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 70 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume70 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.824+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=70 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.825+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 71 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume71 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.878+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=71 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.879+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 72 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume72 Aug 28 09:54:37 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:37 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:37 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:37 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.960+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=72 Aug 28 09:54:37 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:37.960+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:37 rivo volumio[3477]: info: Aug 28 09:54:37 rivo volumio[3477]: ---------------------------- Client requests Volume 72 Aug 28 09:54:37 rivo volumio[3477]: info: VolumeController::SetAlsaVolume72 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.033+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=72 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.033+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 73 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume73 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.124+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=73 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.125+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 74 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume74 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.190+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=74 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.190+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 75 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume75 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.269+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=75 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.270+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 75 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume75 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.346+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=75 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.346+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 76 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume76 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.403+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=76 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.404+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 77 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume77 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.465+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=77 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.466+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 77 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume77 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.536+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=77 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.536+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 78 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume78 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.586+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=78 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.587+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 79 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume79 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.640+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=79 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.640+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 79 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume79 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.713+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=79 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.713+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 80 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume80 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.767+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=80 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.767+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 80 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume80 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.841+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=80 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 81 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume81 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.920+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=81 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.921+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:38 rivo volumio[3477]: info: Aug 28 09:54:38 rivo volumio[3477]: ---------------------------- Client requests Volume 82 Aug 28 09:54:38 rivo volumio[3477]: info: VolumeController::SetAlsaVolume82 Aug 28 09:54:38 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:38 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:38 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:38 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.995+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=82 Aug 28 09:54:38 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:38.996+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 82 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume82 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.044+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=82 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.045+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 83 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume83 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.127+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=83 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.128+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 84 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume84 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.202+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=84 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.202+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 85 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume85 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.260+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=85 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.260+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 86 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume86 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.336+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=86 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.336+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 87 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume87 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=87 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 88 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume88 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.487+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=88 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.487+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 89 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume89 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.924+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=89 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.924+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 90 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume90 Aug 28 09:54:39 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:39 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:39 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:39 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.977+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=90 Aug 28 09:54:39 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:39.978+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:39 rivo volumio[3477]: info: Aug 28 09:54:39 rivo volumio[3477]: ---------------------------- Client requests Volume 91 Aug 28 09:54:39 rivo volumio[3477]: info: VolumeController::SetAlsaVolume91 Aug 28 09:54:40 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:40 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:40 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:40 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:40 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:40.054+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=91 Aug 28 09:54:40 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:40.055+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:40 rivo volumio[3477]: info: Aug 28 09:54:40 rivo volumio[3477]: ---------------------------- Client requests Volume 92 Aug 28 09:54:40 rivo volumio[3477]: info: VolumeController::SetAlsaVolume92 Aug 28 09:54:40 rivo volumio[3477]: info: CoreStateMachine::pushState Aug 28 09:54:40 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:40 rivo volumio[3477]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:54:40 rivo volumio[3477]: info: MRS: Pushing multiroomSync output Aug 28 09:54:40 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:40.110+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" state=STATUS_STOPPED positionMs=0 volume=92 Aug 28 09:54:40 rivo volumio5-onboarding[4275]: time=2026-08-28T09:54:40.111+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:53666,192.168.1.187:45072 @ 0x30012c0" id= title= Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:40 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:40 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:40 rivo volumio[3477]: info: Updating RAAT Signal Path Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 28 09:54:40 rivo volumio[3477]: info: Playing Remote Device: http://192.168.1.30 Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Done setting volume on: Aug 28 09:54:40 rivo volumio[3477]: info: Aug 28 09:54:40 rivo volumio[3477]: ---------------------------- Client requests Volumio play Aug 28 09:54:40 rivo volumio[3477]: info: CoreCommandRouter::volumioPlay Aug 28 09:54:40 rivo volumio[3477]: info: CoreStateMachine::play index undefined Aug 28 09:54:40 rivo volumio[3477]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 09:54:40 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:41 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Aug 28 09:54:41 rivo volumio[3477]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Aug 28 09:54:41 rivo volumio[3477]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:54:41 rivo volumio[3477]: TypeError: Cannot read property 'then' of undefined Aug 28 09:54:41 rivo volumio[3477]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Aug 28 09:54:41 rivo volumio[3477]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Aug 28 09:54:41 rivo volumio[3477]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Aug 28 09:54:41 rivo volumio[3477]: at Socket.emit (events.js:400:28) Aug 28 09:54:41 rivo volumio[3477]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 28 09:54:41 rivo volumio[3477]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 28 09:54:41 rivo volumio[3477]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:54:42 rivo sudo[9758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-28 09:53 Aug 28 09:54:42 rivo sudo[9758]: 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"