Oct 13 19:51:01 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:01 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:01 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:01 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:01 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:01 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:02 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:05 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:05 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:05 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:05 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:05 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:05 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:05 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:09 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:09 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:09 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:09 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:09 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:09 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:09 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:13 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:13 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:13 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:13 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:13 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:13 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:14 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:17 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:18 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:18 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:18 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:18 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:18 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:18 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:22 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:22 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:22 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:22 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:26 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:26 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:26 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:26 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:26 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:26 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:27 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:27.969-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.66:58693 Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.060-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.66:58693 @ 0x24feea0" latency=12.554407ms timeout=20s included=[] excluded=[] Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.061-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.061-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.66:58693 @ 0x24feea0" latency=13.568157ms platform=PLATFORM_IOS version=6.260930.0 Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 19:51:28 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:28 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.070-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" name=Rivoplus Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.075-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" language=en Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.080-07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" timezone=America/Los_Angeles Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.083-07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" available=true connected=true macAddress=02:00:00:19:31:01 ip4Address=192.168.1.69/24 ip6Address= Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.101-07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.102-07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" setupComplete=true Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 19:51:28 rivoplus volumio[3239]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 13 19:51:28 rivoplus volumio[3239]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 13 19:51:28 rivoplus volumio[3239]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 13 19:51:28 rivoplus volumio[3239]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.232-07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" selectedOutputId=5 Oct 13 19:51:28 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:28 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.257-07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" currentVersion=4.208 latestVersion=4.208 Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.257-07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.66:58693 @ 0x24feea0" status=UPDATE_STATUS_NONE progress=0 Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.258-07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" userId= Oct 13 19:51:28 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:28.258-07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" providers=3 Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 19:51:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 19:51:29 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:29.246-07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" plugins=28 Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:29 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:29.253-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" state=STATUS_PLAYING positionMs=135000 volume=100 Oct 13 19:51:29 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:29.254-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" id= title="High Times" Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:29 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:29 rivoplus volumio[3239]: verbose: New Socket.io Connection to 192.168.1.69:3000 from 192.168.1.66 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 19:51:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 19:51:30 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:30 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:30 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:30 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:30 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:30 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:30 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:30.046-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" state=STATUS_PLAYING positionMs=136000 volume=100 Oct 13 19:51:30 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:30.046-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" id= title="High Times" Oct 13 19:51:30 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:30.817-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.66:58693 @ 0x24feea0" latency=6.633004ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 13 19:51:30 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:30.835-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.198-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=http://pushupdates.volumio.org duration=361.302449ms Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.218-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=http://plugins.volumio.org duration=378.554287ms Oct 13 19:51:31 rivoplus sudo[5761]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 19:51:31 rivoplus sudo[5761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:51:31 rivoplus sudo[5761]: pam_unix(sudo:session): session closed for user root Oct 13 19:51:31 rivoplus sudo[5765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 19:51:31 rivoplus sudo[5765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:51:31 rivoplus sudo[5765]: pam_unix(sudo:session): session closed for user root Oct 13 19:51:31 rivoplus volumio[3239]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.418-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=582.361879ms Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.455-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://securetoken.googleapis.com duration=614.25419ms Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.495-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=655.563155ms Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.558-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://www.googleapis.com duration=719.831833ms Oct 13 19:51:31 rivoplus sudo[5773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 19:51:31 rivoplus sudo[5773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:51:31 rivoplus sudo[5774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 19:51:31 rivoplus sudo[5774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:51:31 rivoplus sudo[5774]: pam_unix(sudo:session): session closed for user root Oct 13 19:51:31 rivoplus sudo[5773]: pam_unix(sudo:session): session closed for user root Oct 13 19:51:31 rivoplus volumio[3239]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.728-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://google.com duration=892.328807ms Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 19:51:31 rivoplus volumio[3239]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 19:51:31 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:31 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:31 rivoplus volumio[3239]: info: Listing playlists Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.770-07:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=http://cddb.volumio.org duration=932.784138ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 13 19:51:31 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.887-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.046399455s Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.904-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.065112027s Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.964-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.123371799s Oct 13 19:51:31 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:31.967-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.130609325s Oct 13 19:51:32 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:32.097-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.66:58693 @ 0x24feea0" latency=6.199407ms timeout=10s endpoint=https://database.volumio.cloud duration=1.255147093s Oct 13 19:51:32 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62, ...) Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 19:51:33 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:33 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:33 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:34 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:34 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:34 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:34.057-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" state=STATUS_PLAYING positionMs=140000 volume=100 Oct 13 19:51:34 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:34.058-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" id= title="High Times" Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 19:51:34 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:34 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:34 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:37 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:37.214-07:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Oct 13 19:51:37 rivoplus dbus-daemon[2922]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.20" (uid=0 pid=3892 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3421 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 13 19:51:37 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:37 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:37 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:37 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:37 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:37 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:37 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:37 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:37.863-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" state=STATUS_PLAYING positionMs=144000 volume=100 Oct 13 19:51:37 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:37.864-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693 @ 0x24feea0" id= title="High Times" Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0001, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0001/char0002, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0001/char0004, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0006, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0006/char0007, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0006/char0007/desc0009, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000a, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000a/char000b, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000a/char000d, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000f, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000f/char0010, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000f/char0010/desc0012, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service000f/char0010/desc0013, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0014, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0014/char0015, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0014/char0015/desc0017, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0014/char0015/desc0018, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0019, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0019/char001a, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0019/char001a/desc001c, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service001d, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service001d/char001e, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service001d/char001e/desc0020, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service001d/char0021, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char0024, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char0024/desc0026, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char0027, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char0027/desc0029, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char002a, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service0023/char002a/desc002c, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char002e, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char002e/desc0030, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char002e/desc0031, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char0032, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char0032/desc0034, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char0032/desc0035, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char0036, ...) Oct 13 19:51:38 rivoplus bluealsa[3422]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_76_27_C5_BE_62/service002d/char0036/desc0038, ...) Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:51:40 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:41 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:41 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:41 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:41 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:41.871-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=148000 volume=100 Oct 13 19:51:41 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:41.872-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:51:45 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:45 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:45 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:45 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:45 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:45 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:45 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:45 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:45.882-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=152000 volume=100 Oct 13 19:51:45 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:45.884-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:51:47 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:51:49 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:49 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:49 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:49 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:49 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:49 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:49 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:49 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:49.885-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=156000 volume=100 Oct 13 19:51:49 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:49.886-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 19:51:50 rivoplus volumio[3239]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 19:51:50 rivoplus volumio[3239]: info: Received Get System Version Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 19:51:50 rivoplus volumio[3239]: info: Received Get System Info Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 19:51:50 rivoplus volumio[3239]: info: Discovery: Getting this device information Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:50 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 19:51:53 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:53 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:53 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:53 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:53 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:53 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:53 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:53 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:53.905-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=160000 volume=100 Oct 13 19:51:53 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:53.906-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:51:57 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:51:57 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:51:57 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:51:57 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:51:57 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:51:57 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:51:57 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:51:57 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:57.909-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=164000 volume=100 Oct 13 19:51:57 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:51:57.909-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:01 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:01 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:01 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:01 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:01 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:01 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:01 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:01 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:01.916-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=168000 volume=100 Oct 13 19:52:01 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:01.918-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:05 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:05 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:05 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:05 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:05 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:05 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:05 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:05 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:05.928-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=172000 volume=100 Oct 13 19:52:05 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:05.929-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:09 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:09 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:09 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:09 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:09 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:09 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:09 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:09 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:09.938-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=176000 volume=100 Oct 13 19:52:09 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:09.939-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:13 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:13 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:13 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:13 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:13 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:13 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:13 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:13 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:13.952-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=180000 volume=100 Oct 13 19:52:13 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:13.954-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:16 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:16 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:16 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:16 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:16 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:16 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:16 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:16 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:16.964-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=183000 volume=100 Oct 13 19:52:16 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:16.966-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:20 rivoplus volumio[3239]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:20 rivoplus volumio[3239]: info: Restoring Previous Volume level: undefined undefined true Oct 13 19:52:20 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolume100 Oct 13 19:52:20 rivoplus volumio[3239]: info: Enable softmixer device for audio device number 5 Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::volumioStop Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreStateMachine::stop Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreStateMachine::serviceStop Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::serviceStop Oct 13 19:52:20 rivoplus volumio[3239]: info: Enable softmixer device for audio device undefined Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 19:52:20 rivoplus volumio[3239]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 19:52:20 rivoplus volumio[3239]: info: Disabling external Volume Control Oct 13 19:52:20 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:20 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:20 rivoplus sudo[6084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:20 rivoplus sudo[6085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:20 rivoplus sudo[6084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:20 rivoplus sudo[6085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:20 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:20 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:20 rivoplus sudo[6084]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:20 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:20 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:20 rivoplus vtcs[4395]: [2026-10-13 19:52:20.965] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 13 19:52:20 rivoplus vtcs[4395]: [2026-10-13 19:52:20.966] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 13 19:52:20 rivoplus volumio[3239]: info: Volume configurations have been set Oct 13 19:52:20 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:20 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:20 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 13 19:52:20 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 19:52:21 rivoplus sudo[6094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:21 rivoplus sudo[6094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus sudo[6097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:21 rivoplus sudo[6097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 19:52:21 rivoplus volumio[3239]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 19:52:21 rivoplus volumio[3239]: info: Disabling external Volume Control Oct 13 19:52:21 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:52:21 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:52:21 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.172-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=186000 volume=100 Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.174-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=186000 volume=100 Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.175-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.175-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:21 rivoplus sudo[6094]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus sudo[6097]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus sudo[6127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:21 rivoplus sudo[6127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus sudo[6126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:21 rivoplus sudo[6126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus sudo[6136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:21 rivoplus sudo[6136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus sudo[6127]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::servicePushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.447-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=187000 volume=100 Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.451-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:21 rivoplus sudo[6126]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:21 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:21 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:21 rivoplus sudo[6136]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:21 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:21 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:21 rivoplus sudo[6143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:21 rivoplus sudo[6143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:21 rivoplus sudo[6145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:21 rivoplus sudo[6145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.566-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=187000 volume= Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.567-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:21 rivoplus volumio[3239]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 19:52:21 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:21 rivoplus sudo[6143]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:21 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:21 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:21 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:21 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:21 rivoplus sudo[6152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:21 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:21 rivoplus sudo[6152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:21 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:21 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:21 rivoplus volumio[3239]: info: Software Volume ALSA configuration written Oct 13 19:52:21 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:21 rivoplus qobuz-connect[4188]: 20261013 19:52:21.691 [4188.4188] INFO SampleApp: Stopping Local configuration server Oct 13 19:52:21 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:21 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolume0 Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:21 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.816-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=187000 volume=0 Oct 13 19:52:21 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:21.816-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:21 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:21 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:21 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 13 19:52:21 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolume0 Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:21 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:22 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:22 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:22 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:22.002-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=187000 volume=0 Oct 13 19:52:22 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:22.002-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:22 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:22 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:22 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:22 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:22 rivoplus sudo[6182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:22 rivoplus sudo[6182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:22 rivoplus sudo[6163]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:52:22 rivoplus sudo[6163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:52:22 rivoplus sudo[6163]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus sudo[6187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:22 rivoplus sudo[6187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus sudo[6182]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus sudo[6191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:22 rivoplus sudo[6191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus sudo[6193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:22 rivoplus sudo[6193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:22 rivoplus sudo[6187]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus sudo[6198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:22 rivoplus sudo[6198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus sudo[6199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:22 rivoplus sudo[6199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:22 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:22 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:22 rivoplus sudo[6199]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:22 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:22 rivoplus sudo[6191]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:22 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:22 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:22 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:22 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:22 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:22 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:22 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:22 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:22 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:22 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:22 rivoplus sudo[6193]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus sudo[6209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:22 rivoplus sudo[6209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:22 rivoplus sudo[6207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:22 rivoplus sudo[6207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:22 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:22 rivoplus sudo[6207]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:22 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:22 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:22 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:22 rivoplus sudo[6198]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus sudo[6223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:22 rivoplus sudo[6223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus volumio[3239]: ALSA lib pcm.c:2666:(snd_pcm_open_noupdate) Unknown PCM volumioMultiRoomServer Oct 13 19:52:22 rivoplus volumio[3239]: aplay: main:831: audio open error: No such file or directory Oct 13 19:52:22 rivoplus sudo[6223]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:22 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:22 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:22 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:22 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:22 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:22 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:22 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:22 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 13 19:52:22 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:22 rivoplus sudo[6255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:22 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:22 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:22 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:22 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:22 rivoplus sudo[6255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:22 rivoplus sudo[6258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:22 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:22 rivoplus sudo[6258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:22 rivoplus sudo[6258]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:22 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:22 rivoplus sudo[6264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:22 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:22 rivoplus sudo[6264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 19:52:22 rivoplus volumio[3239]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 19:52:22 rivoplus sudo[6270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:22 rivoplus sudo[6270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:22 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 19:52:22 rivoplus volumio[3239]: info: Disabling external Volume Control Oct 13 19:52:23 rivoplus sudo[6283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:23 rivoplus sudo[6283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus sudo[6287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:23 rivoplus sudo[6287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus sudo[6286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:23 rivoplus sudo[6286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus sudo[6296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:23 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:23 rivoplus sudo[6302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus sudo[6255]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus sudo[6308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:23 rivoplus qobuz-connect[4188]: 20261013 19:52:23.354 [4188.4188] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:52:23 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:52:23 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:23 rivoplus sudo[6270]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:23 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:23 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:23 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:23 rivoplus sudo[6296]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus sudo[6302]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus sudo[6308]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:23 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:23 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:23 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:23 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:23 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:23 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:23.508-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=188000 volume=100 Oct 13 19:52:23 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:23.509-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:23 rivoplus volumio[3239]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 19:52:23 rivoplus sudo[6319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:23 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:23 rivoplus sudo[6321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:23 rivoplus volumio[3239]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:52:23 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:23 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:23 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:23 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:23 rivoplus sudo[6320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:23 rivoplus sudo[6320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:23 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:23 rivoplus sudo[6152]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus sudo[6145]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus sudo[6283]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus sudo[6287]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:23 rivoplus sudo[6286]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:23 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:23 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:52:23 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:23 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:24 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:24 rivoplus sudo[6320]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6321]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6319]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:24 rivoplus sudo[6351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:24 rivoplus sudo[6351]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:24 rivoplus sudo[6356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:24 rivoplus sudo[6358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6356]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus volumio[3239]: info: Executing endpoint qc_getconfig Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:52:24 rivoplus sudo[6363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:24 rivoplus sudo[6363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:24 rivoplus sudo[6364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:24 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:24 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:24 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:24 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:24 rivoplus sudo[6363]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6330]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:52:24 rivoplus sudo[6330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:52:24 rivoplus volumio[3239]: info: Executing endpoint qc_getconfig Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:52:24 rivoplus sudo[6370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:24 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:24 rivoplus sudo[6330]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:24 rivoplus sudo[6372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:24 rivoplus sudo[6373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6358]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.422 [6352.6352] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.428 [6352.6352] INFO VolumeManager: [0xab85f1d8]: Setting new playback volume: 75 Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.429 [6352.6352] INFO VolumeManager: [0xab85f1d8]: Setting new mute state: 0 Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.429 [6352.6352] INFO AudioStreamManager: [0xab85ef30]: Setting new audio download buffer size: 1048576 Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.429 [6352.6352] INFO QobuzConnect: [0xab85faa0]: Client initialized! Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.429 [6352.6352] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 13 19:52:24 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:24 rivoplus volumio[3239]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:52:24 rivoplus volumio[3239]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:52:24 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.457 [6352.6352] INFO LocalConfigManager: [0xab85ec58]: Starting Local Configuration server Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.457 [6352.6352] INFO SampleApp: Starting Local configuration server Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.458 [6352.6352] INFO SampleApp: Connected to UNIX socket client 0xab849908 Oct 13 19:52:24 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:24 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:24 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:24 rivoplus sudo[6364]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:24 rivoplus sudo[6393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:24 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:24 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:24 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:24 rivoplus sudo[6393]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:24 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:24 rivoplus qobuz-connect[6352]: 20261013 19:52:24.572 [6352.6352] INFO SampleApp: Playback volume changed: 75 Oct 13 19:52:24 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:24 rivoplus sudo[6370]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:24 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:24 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:24 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:24 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:24 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:24 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:24 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:24 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:24 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:24 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:24 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:24 rivoplus sudo[6406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:24 rivoplus sudo[6406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:24 rivoplus sudo[6406]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6372]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus sudo[6373]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:24 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:24 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:24 rivoplus sudo[6408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:24 rivoplus sudo[6408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:24 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:24 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:24 rivoplus sudo[6431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:24 rivoplus sudo[6431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus sudo[6435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:24 rivoplus sudo[6435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:24 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:24 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:24 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:24 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:24 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:24 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 13 19:52:25 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:25 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:25 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:25 rivoplus volumio[3239]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 19:52:25 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:25 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:25 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:25 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:25 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:25 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:24 rivoplus sudo[6440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:24 rivoplus sudo[6440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus sudo[6443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:25 rivoplus sudo[6443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:25 rivoplus sudo[6431]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:25 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:25 rivoplus sudo[6440]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:25 rivoplus sudo[6465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:25 rivoplus sudo[6465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:25 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:25 rivoplus sudo[6468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:25 rivoplus sudo[6435]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:25 rivoplus sudo[6468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:25 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:25 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:25 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:25 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolumeNaN Oct 13 19:52:25 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.505-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=188000 volume= Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.506-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:25 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:25 rivoplus sudo[6485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:25 rivoplus sudo[6485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:25 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:25 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:25 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:25 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:25 rivoplus qobuz-connect[6352]: 20261013 19:52:25.599 [6352.6352] INFO SampleApp: Stopping Local configuration server Oct 13 19:52:25 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:25 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:25 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:25 rivoplus sudo[6468]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:25 rivoplus sudo[6489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:25 rivoplus sudo[6489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:25 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolume0 Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.793-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=188000 volume=0 Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.794-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:25 rivoplus volumio[3239]: info: VolumeController::SetAlsaVolume0 Oct 13 19:52:25 rivoplus sudo[6485]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreStateMachine::pushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioPushState Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output update for this device Oct 13 19:52:25 rivoplus volumio[3239]: info: MRS: Pushing multiroomSync output Oct 13 19:52:25 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.925-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" state=STATUS_PLAYING positionMs=188000 volume=0 Oct 13 19:52:25 rivoplus volumio5-onboarding[3892]: time=2026-10-13T19:52:25.925-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58693,00:00:00:00:00:00%01 @ 0x24feea0" id= title="High Times" Oct 13 19:52:25 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:25 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:25 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:26 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:26 rivoplus sudo[6489]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:26 rivoplus volumio[3239]: info: Signalling Playback active due to playback status change Oct 13 19:52:26 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:26 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:26 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:26 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:26 rivoplus sudo[6505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:26 rivoplus sudo[6505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus sudo[6508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:26 rivoplus sudo[6508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus sudo[6508]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus sudo[6505]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus sudo[6523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:26 rivoplus sudo[6523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:26 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:26 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:26 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:26 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:26 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:26 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:26 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:26 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:26 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:26 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:26 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:26 rivoplus sudo[6534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:26 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:26 rivoplus sudo[6534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:26 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:26 rivoplus sudo[6535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:26 rivoplus qobuz-connect[6352]: 20261013 19:52:26.479 [6352.6352] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:52:26 rivoplus sudo[6535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus sudo[6534]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:26 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus sudo[6486]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:52:26 rivoplus sudo[6486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:52:26 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:26 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:52:26 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:26 rivoplus sudo[6486]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:26 rivoplus sudo[6548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:26 rivoplus sudo[6548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:26 rivoplus sudo[6523]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:26 rivoplus sudo[6465]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:26 rivoplus sudo[6553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:26 rivoplus sudo[6553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:26 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:26 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:26 rivoplus sudo[6563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:26 rivoplus sudo[6563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:26 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:26 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:26 rivoplus volumio[3239]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:52:26 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:26 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:26 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:26 rivoplus volumio[3239]: info: Executing endpoint restartRAATSocket Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:26 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:26 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:26 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:26 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:27 rivoplus sudo[6563]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:27 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:27 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:27 rivoplus sudo[6573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:27 rivoplus sudo[6573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:27 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:27 rivoplus volumio[3239]: info: Executing endpoint qc_getconfig Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.149 [6557.6557] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 13 19:52:27 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.162 [6557.6557] INFO VolumeManager: [0xacd1c1d8]: Setting new playback volume: 75 Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.167 [6557.6557] INFO VolumeManager: [0xacd1c1d8]: Setting new mute state: 0 Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.167 [6557.6557] INFO AudioStreamManager: [0xacd1bf30]: Setting new audio download buffer size: 1048576 Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.167 [6557.6557] INFO QobuzConnect: [0xacd1caa0]: Client initialized! Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.168 [6557.6557] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 13 19:52:27 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:27 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.237 [6557.6557] INFO LocalConfigManager: [0xacd1bc58]: Starting Local Configuration server Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.237 [6557.6557] INFO SampleApp: Starting Local configuration server Oct 13 19:52:27 rivoplus qobuz-connect[6557]: 20261013 19:52:27.241 [6557.6557] INFO SampleApp: Stopping Local configuration server Oct 13 19:52:27 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:27 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:27 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:27 rivoplus volumio[3239]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 13 19:52:27 rivoplus volumio[3239]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 13 19:52:27 rivoplus volumio[3239]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:27 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:27 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:27 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=11/SEGV Oct 13 19:52:27 rivoplus systemd[1]: raat-daemon.service: Failed with result 'signal'. Oct 13 19:52:27 rivoplus sudo[6602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:27 rivoplus sudo[6602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:27 rivoplus sudo[6604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:52:27 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:27 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:27 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:27 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:27 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:27 rivoplus sudo[6618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:27 rivoplus sudo[6618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus sudo[6553]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:27 rivoplus sudo[6548]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:27 rivoplus sudo[6624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:27 rivoplus sudo[6624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:27 rivoplus sudo[6622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:27 rivoplus sudo[6629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6618]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus sudo[6629]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus sudo[6631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:27 rivoplus sudo[6631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:27 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:27 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:27 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:27 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:27 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:27 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:27 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:27 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:27 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:27 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:27 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:27 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:27 rivoplus sudo[6602]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus sudo[6624]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:27 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:27 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:27 rivoplus sudo[6622]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus sudo[6642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:27 rivoplus sudo[6642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6642]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:27 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus sudo[6644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:27 rivoplus sudo[6644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:27 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:27 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:27 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:27 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:27 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:27 rivoplus sudo[6586]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:52:27 rivoplus sudo[6586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:52:27 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:27 rivoplus sudo[6656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:27 rivoplus sudo[6656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:27 rivoplus sudo[6586]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:27 rivoplus sudo[6604]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus sudo[6631]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:28 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:28 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:28 rivoplus sudo[6683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:28 rivoplus sudo[6683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus volumio[3239]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 19:52:28 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:28 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:28 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:52:28 rivoplus sudo[6689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:28 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:28 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:28 rivoplus sudo[6689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:52:28 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:28 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus sudo[6656]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:28 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:28 rivoplus sudo[6689]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:28 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:28 rivoplus volumio[3239]: info: TidalConnect service stoped! Oct 13 19:52:28 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:28 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:28 rivoplus sudo[6703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:28 rivoplus sudo[6703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 13 19:52:28 rivoplus sudo[6712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:28 rivoplus sudo[6712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:28 rivoplus sudo[6683]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus sudo[6721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 13 19:52:28 rivoplus sudo[6721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus sudo[6726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:28 rivoplus sudo[6726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus sudo[6726]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:28 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:28 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:28 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:28 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:28 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:28 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:28 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:28 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:28 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:28 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:28 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:28 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:28 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:28 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:28 rivoplus sudo[6712]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus sudo[6721]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:28 rivoplus sudo[6734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 13 19:52:28 rivoplus sudo[6734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus sudo[6736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:28 rivoplus sudo[6736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus sudo[6738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:28 rivoplus sudo[6738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:28 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:28 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:28 rivoplus sudo[6736]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:28 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 13 19:52:28 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 13 19:52:28 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 13 19:52:28 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:28 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:28 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:29 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 13 19:52:29 rivoplus sudo[6734]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus sudo[6748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:29 rivoplus sudo[6748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus sudo[6769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:29 rivoplus sudo[6769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:29 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:29 rivoplus volumio[3239]: info: RAAT: Requesting Headphone Status Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 13 19:52:29 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:29 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:29 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:29 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:29 rivoplus volumio[3239]: info: Raat Daemon started successfully Oct 13 19:52:29 rivoplus volumio[3239]: info: TidalConnect service started! Oct 13 19:52:29 rivoplus volumio[3239]: info: Shairport-Sync Started Oct 13 19:52:29 rivoplus volumio[3239]: info: TidalConnect service started! Oct 13 19:52:29 rivoplus volumio[3239]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:29 rivoplus volumio[3239]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 13 19:52:29 rivoplus volumio[3239]: info: MCU Signalled Headphone Mode Disabled Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 13 19:52:29 rivoplus volumio[3239]: info: Reporting Headphone State: false Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: Updating RAAT Signal Path Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus sudo[6775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:29 rivoplus sudo[6775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus qobuz-connect[6557]: 20261013 19:52:29.256 [6557.6557] INFO SampleApp: shat down connection on UNIX socket Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 19:52:29 rivoplus sudo[6777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 13 19:52:29 rivoplus sudo[6777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Oct 13 19:52:29 rivoplus systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 13 19:52:29 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 13 19:52:29 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:29 rivoplus volumio[3239]: info: Starting Shairport Sync Oct 13 19:52:29 rivoplus volumio[3239]: info: Asound.conf file written Oct 13 19:52:29 rivoplus sudo[6775]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus sudo[6748]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus sudo[6769]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:29 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:29 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:29 rivoplus sudo[6788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 19:52:29 rivoplus sudo[6788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus sudo[6789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:29 rivoplus sudo[6789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:29 rivoplus sudo[6790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 19:52:29 rivoplus sudo[6790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus sudo[6790]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:29 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:29 rivoplus sudo[6573]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:29 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:29 rivoplus volumio[3239]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 13 19:52:29 rivoplus volumio[3239]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 13 19:52:29 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:29 rivoplus volumio[3239]: No state is present for card AMLAUGESOUNDMP1 Oct 13 19:52:29 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:29 rivoplus volumio[3239]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 19:52:29 rivoplus volumio[3239]: Hardware is initialized using a generic method Oct 13 19:52:29 rivoplus volumio[3239]: No state is present for card Device Oct 13 19:52:29 rivoplus volumio[3239]: info: Output device has changed, restarting MPD Oct 13 19:52:29 rivoplus sudo[6703]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:29 rivoplus volumio[3239]: info: Output device has changed, restarting Shairport Sync Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 19:52:29 rivoplus sudo[6801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 19:52:29 rivoplus sudo[6801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus sudo[6777]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:29 rivoplus sudo[6801]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:29 rivoplus volumio[3239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 19:52:29 rivoplus volumio[3239]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: QobuzConnect: setDeactiveState invoked Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::volumioGetState Oct 13 19:52:29 rivoplus volumio[3239]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 19:52:29 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:29 rivoplus volumio[3239]: info: Preparing to generate the ALSA configuration file Oct 13 19:52:29 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 19:52:29 rivoplus sudo[6803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 19:52:29 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 19:52:29 rivoplus sudo[6803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:29 rivoplus sudo[6814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:29 rivoplus sudo[6814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus sudo[6819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 19:52:29 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 13 19:52:29 rivoplus sudo[6819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:29 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 19:52:30 rivoplus volumio[3239]: info: Not Reporting Auto name since its the default one Oct 13 19:52:30 rivoplus sudo[6826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 19:52:30 rivoplus sudo[6826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:30 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:52:30 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:30 rivoplus volumio[3239]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 13 19:52:30 rivoplus volumio[3239]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 19:52:30 rivoplus volumio[3239]: info: Reading ALSA contributions from plugins. Oct 13 19:52:30 rivoplus volumio[3239]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 19:52:30 rivoplus volumio[3239]: info: MPD Permissions set Oct 13 19:52:30 rivoplus volumio[3239]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 13 19:52:30 rivoplus volumio[3239]: Job for vtcs.service canceled. Oct 13 19:52:30 rivoplus volumio[3239]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Oct 13 19:52:30 rivoplus volumio[3239]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 19:52:30 rivoplus volumio[3239]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 13 19:52:30 rivoplus volumio[3239]: Job for vtcs.service canceled. Oct 13 19:52:30 rivoplus volumio[3239]: at ChildProcess.exithandler (node:child_process:421:12) Oct 13 19:52:30 rivoplus volumio[3239]: at ChildProcess.emit (node:events:514:28) Oct 13 19:52:30 rivoplus volumio[3239]: at maybeClose (node:internal/child_process:1105:16) Oct 13 19:52:30 rivoplus volumio[3239]: at Socket. (node:internal/child_process:457:11) Oct 13 19:52:30 rivoplus volumio[3239]: at Socket.emit (node:events:514:28) Oct 13 19:52:30 rivoplus volumio[3239]: at Pipe. (node:net:337:12) { Oct 13 19:52:30 rivoplus volumio[3239]: code: 1, Oct 13 19:52:30 rivoplus volumio[3239]: killed: false, Oct 13 19:52:30 rivoplus volumio[3239]: signal: null, Oct 13 19:52:30 rivoplus volumio[3239]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 13 19:52:30 rivoplus volumio[3239]: stdout: '', Oct 13 19:52:30 rivoplus volumio[3239]: stderr: 'Job for vtcs.service canceled.\n' Oct 13 19:52:30 rivoplus volumio[3239]: } Oct 13 19:52:30 rivoplus volumio[3239]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 19:52:30 rivoplus sudo[6826]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 13 19:52:30 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 19:52:30 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 19:52:30 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 19:52:30 rivoplus sudo[6833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 19:52:30 rivoplus sudo[6833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 19:52:30 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 19:52:30 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:30 rivoplus sudo[6789]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 13 19:52:30 rivoplus systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 13 19:52:30 rivoplus systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 19:52:30 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 13 19:52:30 rivoplus sudo[6788]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 13 19:52:30 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 13 19:52:30 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:30 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 13 19:52:30 rivoplus sudo[6833]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 13 19:52:30 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 13 19:52:30 rivoplus sudo[6839]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 19:52:30 rivoplus sudo[6839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 19:52:30 rivoplus sudo[6839]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus sudo[6814]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:30 rivoplus sudo[6819]: pam_unix(sudo:session): session closed for user root Oct 13 19:52:31 rivoplus sudo[6863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 19:51' Oct 13 19:52:31 rivoplus sudo[6863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"