Oct 03 01:34:00 rivo vtcs[23826]: [2026-10-03 01:34:00.238] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:0 Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.591+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.222:58748 Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.848+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=127.353047ms platform=PLATFORM_IOS version=6.260915.0 Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.849+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=126.938841ms timeout=20s included=[] excluded=[] Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.849+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" Oct 03 01:34:07 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:07 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:07 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.854+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" name=Rivo Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.856+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" language=en Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.860+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" timezone=Europe/London Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.862+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" available=true connected=true macAddress=02:00:00:33:1a:01 ip4Address=192.168.1.96/24 ip6Address= Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.864+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 03 01:34:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:07.864+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" setupComplete=true Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 01:34:07 rivo volumio[3464]: amixer -c 5 info | grep "Volumio Preciso" Oct 03 01:34:08 rivo volumio[3464]: Card sysdefault:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Oct 03 01:34:08 rivo volumio[3464]: amixer -c 5 info | grep "Volumio Preciso" Oct 03 01:34:08 rivo volumio[3464]: Card sysdefault:5 'Preciso'/'Volumio srl Volumio Preciso at usb-xhci-hcd.0.auto-1.4.2, high speed' Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.079+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" selectedOutputId=5 Oct 03 01:34:08 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:08 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:08 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:08 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:08 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:08 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:08 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.096+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" currentVersion=4.208 latestVersion=4.208 Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.097+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" status=UPDATE_STATUS_NONE progress=0 Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.097+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" userId= Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.097+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" providers=3 Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.097+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" plugins=27 Oct 03 01:34:08 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:08 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.102+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:08 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:08.103+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:09 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:09 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:09 rivo volumio[3464]: verbose: New Socket.io Connection to 192.168.1.96:3000 from 192.168.1.222 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 01:34:09 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 01:34:09 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:09.754+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=130.584255ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 03 01:34:09 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:09.880+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s Oct 03 01:34:09 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:09.973+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=http://pushupdates.volumio.org duration=89.931487ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.085+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=205.014076ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.160+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://www.googleapis.com duration=280.117003ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.215+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://google.com duration=335.127559ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.227+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=345.695351ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.233+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://securetoken.googleapis.com duration=352.713004ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.299+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://functions.volumio.cloud duration=418.034059ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.306+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=424.827378ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.337+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://functions.volumio.cloud duration=455.551502ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.355+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=http://cddb.volumio.org duration=475.083955ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.392+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=512.359063ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.417+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=https://database.volumio.cloud duration=534.60607ms Oct 03 01:34:10 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:10.484+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.222:58748 @ 0x1d9c0f0" latency=125.959647ms timeout=10s endpoint=http://plugins.volumio.org duration=600.388001ms Oct 03 01:34:11 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8, ...) Oct 03 01:34:11 rivo sudo[30253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 01:34:11 rivo sudo[30253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:11 rivo sudo[30254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 01:34:11 rivo sudo[30254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:11 rivo sudo[30253]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:11 rivo sudo[30254]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:11 rivo volumio[3464]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.222 UA: Mozilla/5.0 (iPad; CPU OS 16_7_16 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 01:34:12 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 01:34:12 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 01:34:12 rivo sudo[30263]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 01:34:12 rivo sudo[30263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:12 rivo sudo[30265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 01:34:12 rivo sudo[30265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:12 rivo sudo[30263]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:12 rivo sudo[30265]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:13 rivo volumio[3464]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.222 UA: Mozilla/5.0 (iPad; CPU OS 16_7_16 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:13 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::volumioGetQueue Oct 03 01:34:13 rivo volumio[3464]: info: CoreStateMachine::getQueue Oct 03 01:34:13 rivo volumio[3464]: info: CorePlayQueue::getQueue Oct 03 01:34:13 rivo volumio[3464]: info: Listing playlists Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 01:34:13 rivo volumio[3464]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 03 01:34:13 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:13 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:13 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:13 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 01:34:13 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 01:34:14 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:14.187+01:00 level=INFO msg="new address was allocated" component=ble/conn old=6 new=7 Oct 03 01:34:14 rivo dbus-daemon[2932]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.16" (uid=0 pid=3821 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.6" (uid=0 pid=3350 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 03 01:34:14 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 01:34:14 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:14 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:14 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:14 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:14 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:14 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:14 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0001, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0001/char0002, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0001/char0004, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0006, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0006/char0007, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0006/char0007/desc0009, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000a, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000a/char000b, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000a/char000d, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000f, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000f/char0010, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000f/char0010/desc0012, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service000f/char0010/desc0013, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0014, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0014/char0015, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0014/char0015/desc0017, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0014/char0015/desc0018, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0019, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0019/char001a, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0019/char001a/desc001c, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service001d, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service001d/char001e, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service001d/char001e/desc0020, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service001d/char0021, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char0024, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char0024/desc0026, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char0027, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char0027/desc0029, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char002a, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service0023/char002a/desc002c, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char002e, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char002e/desc0030, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char002e/desc0031, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char0032, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char0032/desc0034, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char0032/desc0035, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char0036, ...) Oct 03 01:34:15 rivo bluealsa[3351]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_02_4F_4C_C8_B8/service002d/char0036/desc0038, ...) Oct 03 01:34:15 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 03 01:34:16 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 01:34:16 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:16 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:16 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:16 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:16 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:16 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:16 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:22 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:23 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 01:34:31 rivo volumio[3464]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 03 01:34:31 rivo volumio[3464]: info: Received Get System Version Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 01:34:31 rivo volumio[3464]: info: Received Get System Info Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 01:34:31 rivo volumio[3464]: info: Discovery: Getting this device information Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:31 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:31 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 01:34:41 rivo volumio[3464]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:41 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:41 rivo volumio[3464]: info: Restoring Previous Volume level: 100 false false Oct 03 01:34:41 rivo volumio[3464]: info: Disable softmixer device for audio device Oct 03 01:34:41 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:34:41 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:41 rivo sudo[30385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:34:41 rivo sudo[30385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo sudo[30386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:34:41 rivo sudo[30386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:34:41 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo sudo[30385]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:41 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:41 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo vtcs[23826]: [2026-10-03 01:34:41.499] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 01:34:41 rivo vtcs[23826]: [2026-10-03 01:34:41.500] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 03 01:34:41 rivo vtcs[23826]: [2026-10-03 01:34:41.500] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 01:34:41 rivo volumio[3464]: info: Volume configurations have been set Oct 03 01:34:41 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:41 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:41 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 01:34:41 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 03 01:34:41 rivo sudo[30395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:41 rivo sudo[30395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 01:34:41 rivo volumio[3464]: info: Updating Volume Controller Parameters: Device: 5 Name: Volumio Preciso Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 01:34:41 rivo sudo[30398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:41 rivo sudo[30398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 01:34:41 rivo volumio[3464]: info: Disabling external Volume Control Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 01:34:41 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:34:41 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:34:41 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:34:41 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:41 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:41 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:34:41 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:34:41 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:34:41 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:34:41 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:34:41 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:41.603+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:41 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:41.605+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:41 rivo sudo[30405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:41 rivo sudo[30405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo sudo[30407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:41 rivo sudo[30407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:34:41 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:34:41 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 01:34:41 rivo volumio[3464]: info: Soft Volume ALSA configuration file deleted Oct 03 01:34:41 rivo volumio[3464]: info: Preparing to generate the ALSA configuration file Oct 03 01:34:41 rivo sudo[30420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:34:41 rivo sudo[30420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 01:34:41 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 01:34:41 rivo sudo[30430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:34:41 rivo sudo[30430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:41 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:34:41 rivo volumio[3464]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 01:34:41 rivo volumio[3464]: info: Reading ALSA contributions from plugins. Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:34:41 rivo sudo[30395]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo sudo[30420]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo sudo[30430]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo sudo[30441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:34:41 rivo sudo[30441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo sudo[30440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:34:41 rivo sudo[30440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 01:34:41 rivo sudo[30405]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo sudo[30407]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 01:34:41 rivo sudo[30398]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:41 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:41 rivo qobuz-connect[23805]: 20261003 01:34:41.995 [23805.23805] INFO SampleApp: Stopping Local configuration server Oct 03 01:34:41 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:34:42 rivo sudo[30452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:34:42 rivo sudo[30452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:34:42 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:34:42 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:34:42 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:34:42 rivo volumio[3464]: info: Asound.conf file written Oct 03 01:34:42 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:42 rivo sudo[30452]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 01:34:42 rivo sudo[30464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30464]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 01:34:42 rivo kernel: Err: can not set rate to 4258967296! Oct 03 01:34:42 rivo kernel: Range[3920000 - 500000000] Oct 03 01:34:42 rivo kernel: out of value, fixed it Oct 03 01:34:42 rivo kernel: id=0 set inskew=0 Oct 03 01:34:42 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:34:42 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:34:42 rivo volumio[3464]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 01:34:42 rivo volumio[3464]: Hardware is initialized using a generic method Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:34:42 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:42 rivo sudo[30416]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:34:42 rivo sudo[30416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:34:42 rivo sudo[30484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:34:42 rivo sudo[30484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:34:42 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo sudo[30416]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:34:42 rivo sudo[30487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30484]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:42 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:42 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: Preparing to generate the ALSA configuration file Oct 03 01:34:42 rivo sudo[30496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:42 rivo sudo[30496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:42 rivo sudo[30500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo volumio[3464]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 01:34:42 rivo volumio[3464]: info: Reading ALSA contributions from plugins. Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:34:42 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:34:42 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:34:42 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:34:42 rivo sudo[30505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:34:42 rivo sudo[30505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:34:42 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:34:42 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:34:42 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:34:42 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:34:42 rivo sudo[30510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:34:42 rivo sudo[30510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo sudo[30519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:34:42 rivo sudo[30519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:34:42 rivo sudo[30496]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:34:42 rivo sudo[30500]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo volumio[3464]: info: Asound.conf file written Oct 03 01:34:42 rivo sudo[30505]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30510]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:34:42 rivo sudo[30529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30519]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 01:34:42 rivo sudo[30533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:34:42 rivo sudo[30532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:34:42 rivo sudo[30534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo sudo[30533]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:34:42 rivo sudo[30536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:34:42 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:34:42 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:42 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 01:34:42 rivo kernel: Err: can not set rate to 4254967296! Oct 03 01:34:42 rivo kernel: Range[3920000 - 500000000] Oct 03 01:34:42 rivo kernel: out of value, fixed it Oct 03 01:34:42 rivo kernel: id=0 set inskew=0 Oct 03 01:34:42 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:34:42 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:34:42 rivo volumio[3464]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 01:34:42 rivo volumio[3464]: Hardware is initialized using a generic method Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 01:34:42 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:34:42 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:42 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:34:42 rivo sudo[30544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:34:42 rivo sudo[30544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:42 rivo sudo[30529]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30544]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:42 rivo sudo[30547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:34:42 rivo sudo[30547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo qobuz-connect[23805]: 20261003 01:34:42.858 [23805.23805] INFO SampleApp: shat down connection on UNIX socket Oct 03 01:34:42 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:34:42 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:34:42 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:34:42 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:34:42 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:42 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:42 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:42 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:34:42 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:42 rivo sudo[30558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:42 rivo sudo[30558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:42 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:34:42 rivo sudo[30559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:42 rivo sudo[30559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo sudo[30536]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo sudo[30534]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo sudo[30584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:34:43 rivo sudo[30584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:43 rivo sudo[30440]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo sudo[30532]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo sudo[30441]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:34:43 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:34:43 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:34:43 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:34:43 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 01:34:43 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:43 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:43 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:34:43 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:34:43 rivo sudo[30517]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:34:43 rivo sudo[30517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:34:43 rivo sudo[30517]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo sudo[30584]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:34:43 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:34:43 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:34:43 rivo sudo[30602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:34:43 rivo sudo[30602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:34:43 rivo sudo[30558]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo sudo[30559]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:43 rivo volumio[3464]: info: Executing endpoint qc_getconfig Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 01:34:43 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:34:43 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:34:43 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:34:43 rivo sudo[30612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:34:43 rivo sudo[30612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.401 [30591.30591] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.407 [30591.30591] INFO VolumeManager: [0xac8db3c8]: Setting new playback volume: 75 Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.407 [30591.30591] INFO VolumeManager: [0xac8db3c8]: Setting new mute state: 0 Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.407 [30591.30591] INFO AudioStreamManager: [0xac8db120]: Setting new audio download buffer size: 1048576 Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.407 [30591.30591] INFO QobuzConnect: [0xac8dbc90]: Client initialized! Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.407 [30591.30591] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 03 01:34:43 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.438 [30591.30591] INFO LocalConfigManager: [0xac8dae48]: Starting Local Configuration server Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.438 [30591.30591] INFO SampleApp: Starting Local configuration server Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.439 [30591.30591] INFO SampleApp: Connected to UNIX socket client 0xac8c5908 Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:43 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:34:43 rivo sudo[30612]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:34:43 rivo qobuz-connect[30591]: 20261003 01:34:43.471 [30591.30591] INFO SampleApp: Stopping Local configuration server Oct 03 01:34:43 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:34:43 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 01:34:43 rivo volumio[3464]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 01:34:43 rivo volumio[3464]: 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 03 01:34:43 rivo sudo[30624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:34:43 rivo sudo[30624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:34:43 rivo sudo[30626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:34:43 rivo sudo[30626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:34:43 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:34:43 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:34:43 rivo sudo[30632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:34:43 rivo sudo[30632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:34:43 rivo sudo[30624]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:34:43 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:34:43 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:34:43 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:43 rivo sudo[30632]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:34:43 rivo sudo[30626]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:34:43 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:34:43 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:34:43 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:43 rivo volumio[3464]: 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 03 01:34:43 rivo sudo[30644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:34:43 rivo sudo[30644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:43 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:34:43 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:34:43 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:34:43 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:34:43 rivo sudo[30644]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:43 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:34:44 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:34:44 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:34:44 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:44 rivo volumio[3464]: 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 03 01:34:44 rivo sudo[30618]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:34:44 rivo sudo[30618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:34:44 rivo sudo[30618]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:44 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:44 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:44 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:44 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:44 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:44 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:45 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:45 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:45 rivo sudo[30707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:34:45 rivo sudo[30707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:45 rivo sudo[30709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:34:45 rivo sudo[30709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:45 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 01:34:45 rivo sudo[30707]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:45 rivo sudo[30709]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:45 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:34:45 rivo qobuz-connect[30591]: 20261003 01:34:45.451 [30591.30591] INFO SampleApp: shat down connection on UNIX socket Oct 03 01:34:45 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 01:34:45 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:34:45 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:45 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:34:45 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:45 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:45 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:34:45 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:45 rivo sudo[30602]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:45 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:34:45 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:45 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:45 rivo volumio[3464]: info: Executing endpoint tc_getconfig Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 01:34:45 rivo vtcs[30715]: STARTING TidalConnect services, version: 1.6.1 Oct 03 01:34:45 rivo vtcs[30715]: STARTED TidalConnect services. Oct 03 01:34:45 rivo volumio[3464]: info: Executing endpoint tc_connect Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 03 01:34:45 rivo volumio[3464]: info: Connecting to TidalConnect Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::servicePushState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo volumio[3464]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::servicePushState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo volumio[3464]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 03 01:34:45 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:45 rivo volumio[3464]: info: Executing endpoint qc_getconfig Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.633 [30716.30716] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 01:34:45 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.639 [30716.30716] INFO VolumeManager: [0xabba93c8]: Setting new playback volume: 75 Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.640 [30716.30716] INFO VolumeManager: [0xabba93c8]: Setting new mute state: 0 Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.640 [30716.30716] INFO AudioStreamManager: [0xabba9120]: Setting new audio download buffer size: 1048576 Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.640 [30716.30716] INFO QobuzConnect: [0xabba9c90]: Client initialized! Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.640 [30716.30716] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 03 01:34:45 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 01:34:45 rivo volumio[3464]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.663 [30716.30716] INFO LocalConfigManager: [0xabba8e48]: Starting Local Configuration server Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.663 [30716.30716] INFO SampleApp: Starting Local configuration server Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.663 [30716.30716] INFO SampleApp: Connected to UNIX socket client 0xabb93908 Oct 03 01:34:45 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:45 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:45 rivo sudo[30745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:34:45 rivo sudo[30745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:45 rivo sudo[30745]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:45 rivo qobuz-connect[30716]: 20261003 01:34:45.783 [30716.30716] INFO SampleApp: Playback volume changed: 75 Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo volumio[3464]: info: VolumeController::SetAlsaVolume100 Oct 03 01:34:45 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:34:45 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:45 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:45 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:34:45 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:34:45 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:45.935+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:45 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:45.936+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:45 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:46 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:46 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:46 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:34:46 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:46 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:46 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:34:46 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:46 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:46 rivo sudo[30757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:34:46 rivo sudo[30757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:46 rivo sudo[30757]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:46 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:34:46 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:34:46 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:46 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:46 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:47 rivo mpd[30672]: 2026-10-03T01:34:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 03 01:34:47 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 03 01:34:47 rivo sudo[30386]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:47 rivo sudo[30487]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:47 rivo sudo[30547]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:47 rivo volumio[3464]: error: MPD error: The expression evaluated to a falsy value: Oct 03 01:34:47 rivo volumio[3464]: assert.ok(self.idling) Oct 03 01:34:47 rivo volumio[3464]: error: The expression evaluated to a falsy value: Oct 03 01:34:47 rivo volumio[3464]: assert.ok(self.idling) Oct 03 01:34:47 rivo volumio[3464]: error: MPD error: The expression evaluated to a falsy value: Oct 03 01:34:47 rivo volumio[3464]: assert.ok(self.idling) Oct 03 01:34:47 rivo volumio[3464]: error: The expression evaluated to a falsy value: Oct 03 01:34:47 rivo volumio[3464]: assert.ok(self.idling) Oct 03 01:34:47 rivo volumio[3464]: error: updateQueue error: null Oct 03 01:34:48 rivo volumio[3464]: info: TidalConnect service started! Oct 03 01:34:48 rivo volumio[3464]: info: TidalConnect service started! Oct 03 01:34:48 rivo volumio[3464]: info: TidalConnect service started! Oct 03 01:34:49 rivo volumio[3464]: info: TidalConnect service started! Oct 03 01:34:49 rivo volumio[3464]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:49 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:49 rivo volumio[3464]: info: Restoring Previous Volume level: 100 false false Oct 03 01:34:49 rivo volumio[3464]: info: Volume configurations have been set Oct 03 01:34:49 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:49 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:49 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 01:34:49 rivo vtcs[30715]: [2026-10-03 01:34:49.563] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 01:34:49 rivo vtcs[30715]: [2026-10-03 01:34:49.563] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 01:34:49 rivo volumio[3464]: info: Updating Volume Controller Parameters: Device: 5 Name: Volumio Preciso Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 01:34:49 rivo volumio[3464]: info: Disabling external Volume Control Oct 03 01:34:49 rivo volumio[3464]: info: VolumeController:: Starting alsactl monitor Oct 03 01:34:49 rivo sudo[30772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:49 rivo sudo[30772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 01:34:49 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 01:34:49 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 01:34:49 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 01:34:49 rivo sudo[30772]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:49 rivo sudo[30778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:34:49 rivo sudo[30778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:49 rivo sudo[30785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:34:49 rivo sudo[30785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:49 rivo sudo[30778]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 01:34:49 rivo sudo[30785]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:49 rivo sudo[30800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:34:49 rivo sudo[30800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 01:34:49 rivo volumio[3464]: info: VolumeController:: Volume=100 Mute =false Oct 03 01:34:49 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:34:49 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:49 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:49 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:34:49 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:34:49 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:49.853+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:49 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:49.854+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:49 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:34:49 rivo qobuz-connect[30716]: 20261003 01:34:49.869 [30716.30716] INFO SampleApp: Stopping Local configuration server Oct 03 01:34:49 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:49 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:34:50 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:34:50 rivo sudo[30811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:34:50 rivo sudo[30811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:50 rivo sudo[30811]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:50 rivo sudo[30813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:34:50 rivo sudo[30813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:50 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:34:50 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:34:50 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:34:50 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:34:50 rivo sudo[30813]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:50 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:34:50 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:34:50 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:34:50 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:50 rivo volumio[3464]: 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 03 01:34:51 rivo qobuz-connect[30716]: 20261003 01:34:51.675 [30716.30716] INFO SampleApp: shat down connection on UNIX socket Oct 03 01:34:51 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 01:34:51 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:34:51 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:51 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:51 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:34:51 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:51 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:34:51 rivo sudo[30800]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:51 rivo volumio[3464]: info: Executing endpoint qc_getconfig Oct 03 01:34:51 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.809 [30829.30829] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 01:34:51 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 01:34:51 rivo volumio[3464]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.816 [30829.30829] INFO VolumeManager: [0xad14a3c8]: Setting new playback volume: 75 Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.817 [30829.30829] INFO VolumeManager: [0xad14a3c8]: Setting new mute state: 0 Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.817 [30829.30829] INFO AudioStreamManager: [0xad14a120]: Setting new audio download buffer size: 1048576 Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.817 [30829.30829] INFO QobuzConnect: [0xad14ac90]: Client initialized! Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.817 [30829.30829] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.829 [30829.30829] INFO LocalConfigManager: [0xad149e48]: Starting Local Configuration server Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.829 [30829.30829] INFO SampleApp: Starting Local configuration server Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.830 [30829.30829] INFO SampleApp: Connected to UNIX socket client 0xad134908 Oct 03 01:34:51 rivo qobuz-connect[30829]: 20261003 01:34:51.959 [30829.30829] INFO SampleApp: Playback volume changed: 75 Oct 03 01:34:51 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:51 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:52 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:52 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:34:52 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:52 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:34:52 rivo sudo[30845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:34:52 rivo sudo[30845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:34:52 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 01:34:52 rivo sudo[30845]: pam_unix(sudo:session): session closed for user root Oct 03 01:34:53 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:34:53 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:34:53 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:53 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:53 rivo volumio[3464]: info: Executing endpoint tc_getconfig Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 01:34:53 rivo vtcs[30849]: STARTING TidalConnect services, version: 1.6.1 Oct 03 01:34:53 rivo vtcs[30849]: STARTED TidalConnect services. Oct 03 01:34:53 rivo volumio[3464]: info: Executing endpoint tc_connect Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 03 01:34:53 rivo volumio[3464]: info: Connecting to TidalConnect Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::servicePushState Oct 03 01:34:53 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:53 rivo volumio[3464]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 03 01:34:53 rivo volumio[3464]: info: CoreCommandRouter::servicePushState Oct 03 01:34:53 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:53 rivo volumio[3464]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 03 01:34:54 rivo volumio[3464]: info: VolumeController::SetAlsaVolume100 Oct 03 01:34:54 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:34:54 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:54 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:34:54 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:54 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:54 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:34:54 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:34:54 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:54.081+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:54 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:54.081+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:54 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:54 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:34:54 rivo volumio[3464]: info: VolumeController:: Setting startup Volume 100 Oct 03 01:34:54 rivo volumio[3464]: info: VolumeController::SetAlsaVolume100 Oct 03 01:34:54 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:34:54 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:54 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:34:54 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:34:54 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:34:54 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:34:54 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:34:54 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:54.628+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_PAUSED positionMs=251 volume=100 Oct 03 01:34:54 rivo volumio5-onboarding[3821]: time=2026-10-03T01:34:54.629+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:34:54 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:34:55 rivo volumio[3464]: info: TidalConnect service started! Oct 03 01:35:02 rivo volumio[3464]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: Restoring Previous Volume level: 100 false true Oct 03 01:35:02 rivo volumio[3464]: info: VolumeController::SetAlsaVolume100 Oct 03 01:35:02 rivo volumio[3464]: info: Enable softmixer device for audio device number 5 Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioStop Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::stop Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::updateTrackBlock Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrackBlock Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::stPlaybackTimer Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::serviceStop Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::serviceStop Oct 03 01:35:02 rivo volumio[3464]: info: [1790987702556] ControllerWebradio::stop Oct 03 01:35:02 rivo volumio[3464]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 01:35:02 rivo volumio[3464]: info: Enable softmixer device for audio device undefined Oct 03 01:35:02 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:35:02 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:02 rivo sudo[30904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:35:02 rivo sudo[30904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo sudo[30906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:02 rivo sudo[30906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo sudo[30904]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:02 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:02 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:02 rivo vtcs[30849]: [2026-10-03 01:35:02.631] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 01:35:02 rivo vtcs[30849]: [2026-10-03 01:35:02.631] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 03 01:35:02 rivo vtcs[30849]: [2026-10-03 01:35:02.631] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 01:35:02 rivo volumio[3464]: info: Volume configurations have been set Oct 03 01:35:02 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 01:35:02 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 03 01:35:02 rivo sudo[30915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:02 rivo sudo[30915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo sudo[30917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:02 rivo sudo[30917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 01:35:02 rivo volumio[3464]: info: Updating Volume Controller Parameters: Device: 5 Name: Volumio Preciso Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 01:35:02 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:35:02 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 01:35:02 rivo volumio[3464]: info: Disabling external Volume Control Oct 03 01:35:02 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:35:02 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:35:02 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.744+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.744+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.745+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.746+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:35:02 rivo volumio[3464]: error: MPD error: Error: write EPIPE Oct 03 01:35:02 rivo volumio[3464]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Oct 03 01:35:02 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:35:02 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:35:02 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 01:35:02 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 01:35:02 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 01:35:02 rivo sudo[30936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:02 rivo sudo[30936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo sudo[30939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:02 rivo sudo[30939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo sudo[30915]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:02 rivo sudo[30946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:02 rivo sudo[30946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:35:02 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:35:02 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:35:02 rivo sudo[30953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:02 rivo sudo[30946]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:02 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:02 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:35:02 rivo sudo[30953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:02 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.956+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 01:35:02 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:02.957+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:35:02 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:35:02 rivo volumio[3464]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 03 01:35:02 rivo volumio[3464]: info: Software Volume ALSA configuration written Oct 03 01:35:02 rivo volumio[3464]: info: Preparing to generate the ALSA configuration file Oct 03 01:35:02 rivo sudo[30955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:02 rivo sudo[30955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo volumio[3464]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 01:35:03 rivo volumio[3464]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 01:35:03 rivo volumio[3464]: info: Reading ALSA contributions from plugins. Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:35:03 rivo sudo[30917]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[30936]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo sudo[30939]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 01:35:03 rivo sudo[30953]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 01:35:03 rivo qobuz-connect[30829]: 20261003 01:35:03.111 [30829.30829] INFO SampleApp: Stopping Local configuration server Oct 03 01:35:03 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:35:03 rivo sudo[30968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:03 rivo sudo[30968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:03 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:03 rivo sudo[30981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:35:03 rivo sudo[30981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:03 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:35:03 rivo sudo[30986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:03 rivo sudo[30986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:35:03 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:03 rivo sudo[30938]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:35:03 rivo sudo[30938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:35:03 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:03 rivo sudo[30938]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[30981]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:35:03 rivo volumio[3464]: info: Asound.conf file written Oct 03 01:35:03 rivo sudo[30986]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[30994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:03 rivo sudo[30994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[30998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 01:35:03 rivo sudo[30997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:03 rivo sudo[30997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[30998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[30998]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[30994]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:35:03 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:35:03 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:35:03 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:35:03 rivo sudo[30997]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 01:35:03 rivo kernel: Err: can not set rate to 4250967296! Oct 03 01:35:03 rivo kernel: Range[3920000 - 500000000] Oct 03 01:35:03 rivo kernel: out of value, fixed it Oct 03 01:35:03 rivo kernel: id=0 set inskew=0 Oct 03 01:35:03 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:03 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:03 rivo volumio[3464]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 01:35:03 rivo volumio[3464]: Hardware is initialized using a generic method Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:35:03 rivo sudo[31017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:03 rivo sudo[31017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[31025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:35:03 rivo sudo[31025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[31025]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:35:03 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:03 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:35:03 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:35:03 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:35:03 rivo sudo[31017]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[31031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:03 rivo sudo[31031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:35:03 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:03 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:03 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 03 01:35:03 rivo volumio[3464]: aplay: main:831: audio open error: Device or resource busy Oct 03 01:35:03 rivo sudo[31044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:03 rivo sudo[31044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 01:35:03 rivo kernel: Err: can not set rate to 4246967296! Oct 03 01:35:03 rivo kernel: Range[3920000 - 500000000] Oct 03 01:35:03 rivo kernel: out of value, fixed it Oct 03 01:35:03 rivo kernel: id=0 set inskew=0 Oct 03 01:35:03 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:03 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:03 rivo volumio[3464]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 01:35:03 rivo volumio[3464]: Hardware is initialized using a generic method Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 01:35:03 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:03 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:03 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 01:35:03 rivo qobuz-connect[30829]: 20261003 01:35:03.848 [30829.30829] INFO SampleApp: shat down connection on UNIX socket Oct 03 01:35:03 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:35:03 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:35:03 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:03 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:03 rivo sudo[31052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:03 rivo sudo[31052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:35:03 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:35:03 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:03 rivo sudo[31056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:03 rivo sudo[31056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[31054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:35:03 rivo sudo[31054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:03 rivo sudo[31054]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:03 rivo sudo[30968]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:03 rivo sudo[30955]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:04 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:04 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:35:04 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:35:04 rivo sudo[31067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:04 rivo sudo[31067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 01:35:04 rivo volumio[3464]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 01:35:04 rivo sudo[31044]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 01:35:04 rivo volumio[3464]: info: Disabling external Volume Control Oct 03 01:35:04 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:35:04 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:35:04 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:35:04 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:35:04 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:35:04 rivo sudo[31083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:04 rivo sudo[31083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:04 rivo sudo[31084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:04 rivo sudo[31087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:04 rivo sudo[31096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31052]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo sudo[31096]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo sudo[31102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:04 rivo sudo[31102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:04 rivo sudo[31106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:04 rivo sudo[31109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:04 rivo sudo[31109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:04 rivo sudo[31083]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo sudo[31084]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:35:04 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:35:04 rivo sudo[31067]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:04 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:04 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:35:04 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:35:04 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:04.704+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 01:35:04 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:04.704+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:35:04 rivo volumio[3464]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 01:35:04 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:35:04 rivo volumio[3464]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 01:35:04 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:35:04 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 01:35:04 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:04 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:04 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:35:04 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:35:04 rivo sudo[31087]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo sudo[31102]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:04 rivo volumio[3464]: info: Executing endpoint qc_getconfig Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 01:35:04 rivo sudo[31109]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:04 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:35:04 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:35:04 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:04 rivo sudo[31130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:04 rivo sudo[31130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo sudo[31132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:04 rivo sudo[31132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:04 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:04 rivo sudo[31106]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:35:05 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:35:05 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:05 rivo sudo[31132]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31130]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:35:05 rivo sudo[31082]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:35:05 rivo sudo[31082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:05 rivo sudo[31082]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:05 rivo sudo[31178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:05 rivo sudo[31178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:05 rivo sudo[31182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:05 rivo sudo[31182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo sudo[31178]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:35:05 rivo sudo[31186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:05 rivo sudo[31186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:35:05 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:35:05 rivo sudo[31188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:05 rivo sudo[31188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: Executing endpoint qc_getconfig Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 01:35:05 rivo sudo[31182]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:35:05 rivo sudo[31195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo sudo[31193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:35:05 rivo sudo[31193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.359 [31163.31163] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 01:35:05 rivo sudo[31188]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.370 [31163.31163] INFO VolumeManager: [0xabb173c8]: Setting new playback volume: 75 Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.370 [31163.31163] INFO VolumeManager: [0xabb173c8]: Setting new mute state: 0 Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.370 [31163.31163] INFO AudioStreamManager: [0xabb17120]: Setting new audio download buffer size: 1048576 Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.370 [31163.31163] INFO QobuzConnect: [0xabb17c90]: Client initialized! Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.370 [31163.31163] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 03 01:35:05 rivo sudo[31198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:05 rivo sudo[31198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: Preparing to generate the ALSA configuration file Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.393 [31163.31163] INFO LocalConfigManager: [0xabb16e48]: Starting Local Configuration server Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.393 [31163.31163] INFO SampleApp: Starting Local configuration server Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.394 [31163.31163] INFO SampleApp: Connected to UNIX socket client 0xabb01908 Oct 03 01:35:05 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:35:05 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:35:05 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:35:05 rivo sudo[31201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:05 rivo sudo[31201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:05 rivo volumio[3464]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 01:35:05 rivo volumio[3464]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 01:35:05 rivo volumio[3464]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 01:35:05 rivo volumio[3464]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 01:35:05 rivo volumio[3464]: info: Reading ALSA contributions from plugins. Oct 03 01:35:05 rivo volumio[3464]: 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 03 01:35:05 rivo volumio[3464]: info: Asound.conf file written Oct 03 01:35:05 rivo qobuz-connect[31163]: 20261003 01:35:05.509 [31163.31163] INFO SampleApp: Playback volume changed: 75 Oct 03 01:35:05 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:35:05 rivo sudo[31186]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31198]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 01:35:05 rivo sudo[31207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:35:05 rivo sudo[31207]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:35:05 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:05 rivo sudo[31201]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:05 rivo sudo[31193]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31195]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 01:35:05 rivo kernel: Err: can not set rate to 4242967296! Oct 03 01:35:05 rivo kernel: Range[3920000 - 500000000] Oct 03 01:35:05 rivo kernel: out of value, fixed it Oct 03 01:35:05 rivo kernel: id=0 set inskew=0 Oct 03 01:35:05 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:05 rivo volumio[3464]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 01:35:05 rivo volumio[3464]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 01:35:05 rivo volumio[3464]: Hardware is initialized using a generic method Oct 03 01:35:05 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 01:35:05 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 01:35:05 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 01:35:05 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 01:35:05 rivo volumio[3464]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 01:35:05 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:35:05 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:05 rivo sudo[31219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:35:05 rivo sudo[31219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:35:05 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo sudo[31219]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:05 rivo sudo[31220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:05 rivo sudo[31220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:05 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:05 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:05 rivo volumio[3464]: info: Preparing to generate the ALSA configuration file Oct 03 01:35:05 rivo sudo[31243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:05 rivo sudo[31243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo sudo[31247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:05 rivo sudo[31247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 03 01:35:05 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:35:05 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:35:05 rivo volumio[3464]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 01:35:05 rivo volumio[3464]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 01:35:05 rivo volumio[3464]: info: Reading ALSA contributions from plugins. Oct 03 01:35:05 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:05 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:06 rivo sudo[31243]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:06 rivo volumio[3464]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 01:35:06 rivo sudo[31252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:06 rivo sudo[31252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:35:06 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:35:06 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:35:06 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:35:06 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:35:06 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:35:06 rivo volumio[3464]: info: Raat Daemon started successfully Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo sudo[31254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:06 rivo sudo[31254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:35:06 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:35:06 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:35:06 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:35:06 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: error: Upnp client error: Error: read ECONNRESET Oct 03 01:35:06 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:35:06 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:35:06 rivo sudo[31247]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:06 rivo sudo[31252]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:06 rivo volumio[3464]: info: TidalConnect service stoped! Oct 03 01:35:06 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:35:06 rivo volumio[3464]: info: Asound.conf file unchanged, so no further update is needed Oct 03 01:35:06 rivo volumio[3464]: info: Output device has changed, restarting MPD Oct 03 01:35:06 rivo sudo[31272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:06 rivo sudo[31272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo volumio[3464]: info: Output device has changed, restarting Shairport Sync Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:06 rivo sudo[31278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:35:06 rivo sudo[31279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 01:35:06 rivo sudo[31279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo sudo[31278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo sudo[31279]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:06 rivo volumio[3464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 01:35:06 rivo volumio[3464]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo sudo[31282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 01:35:06 rivo sudo[31282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo qobuz-connect[31163]: 20261003 01:35:06.371 [31163.31163] INFO SampleApp: Stopping Local configuration server Oct 03 01:35:06 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 01:35:06 rivo volumio[3464]: info: QobuzConnect: setDeactiveState invoked Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:06 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:06 rivo volumio[3464]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo sudo[31293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:06 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:35:06 rivo sudo[31293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:35:06 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:06 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 03 01:35:06 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 01:35:06 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 01:35:06 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 01:35:06 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 01:35:06 rivo sudo[31294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 01:35:06 rivo sudo[31294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 01:35:06 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 01:35:06 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:35:06 rivo volumio[3464]: info: RAAT: Requesting Headphone Status Oct 03 01:35:06 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 01:35:06 rivo sudo[31278]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:06 rivo volumio[3464]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 01:35:06 rivo volumio[3464]: info: MPD Permissions set Oct 03 01:35:06 rivo volumio[3464]: info: Shairport-Sync Started Oct 03 01:35:06 rivo sudo[31304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 01:35:06 rivo sudo[31304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:35:06 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:35:06 rivo volumio[3464]: info: MCU Signalled Headphone Mode Disabled Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 01:35:06 rivo volumio[3464]: info: Reporting Headphone State: false Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: Updating RAAT Signal Path Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:06 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:35:06 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:35:06 rivo volumio[3464]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:35:06 rivo volumio[3464]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 01:35:06 rivo volumio[3464]: info: Starting Shairport Sync Oct 03 01:35:06 rivo sudo[31341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:06 rivo sudo[31341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo sudo[31344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:35:06 rivo sudo[31344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 01:35:06 rivo volumio[3464]: info: Not Reporting Auto name since its the default one Oct 03 01:35:06 rivo sudo[31346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 01:35:06 rivo sudo[31346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo sudo[31349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 01:35:06 rivo sudo[31349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:06 rivo volumio[3464]: info: Executing endpoint restartRAATSocket Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 01:35:06 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:06 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 01:35:07 rivo sudo[31304]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo volumio[3464]: 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 03 01:35:07 rivo sudo[31341]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo volumio[3464]: info: VolumeController::SetAlsaVolume100 Oct 03 01:35:07 rivo volumio[3464]: info: CoreStateMachine::pushState Oct 03 01:35:07 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:07 rivo volumio[3464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 01:35:07 rivo volumio[3464]: info: CoreCommandRouter::volumioPushState Oct 03 01:35:07 rivo volumio[3464]: info: CoreCommandRouter::volumioGetState Oct 03 01:35:07 rivo volumio[3464]: info: CorePlayQueue::getTrack 0 Oct 03 01:35:07 rivo volumio[3464]: info: MRS: Pushing multiroomSync output update for this device Oct 03 01:35:07 rivo volumio[3464]: info: MRS: Pushing multiroomSync output Oct 03 01:35:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:07.078+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 01:35:07 rivo volumio5-onboarding[3821]: time=2026-10-03T01:35:07.079+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.222:58748,00:00:00:00:00:00%06 @ 0x1d9c0f0" id="http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=128000.norewind.m3u8" title="BBC Radio 4" Oct 03 01:35:07 rivo sudo[31293]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 01:35:07 rivo sudo[31365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:07 rivo sudo[31364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 01:35:07 rivo sudo[31364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:07 rivo sudo[31294]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:07 rivo sudo[31366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:07 rivo volumio[3464]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 03 01:35:07 rivo volumio[3464]: Job for vtcs.service canceled. Oct 03 01:35:07 rivo volumio[3464]: {"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 03 01:35:07 rivo volumio[3464]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 01:35:07 rivo volumio[3464]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 03 01:35:07 rivo volumio[3464]: Job for vtcs.service canceled. Oct 03 01:35:07 rivo volumio[3464]: at ChildProcess.exithandler (node:child_process:421:12) Oct 03 01:35:07 rivo volumio[3464]: at ChildProcess.emit (node:events:514:28) Oct 03 01:35:07 rivo volumio[3464]: at maybeClose (node:internal/child_process:1105:16) Oct 03 01:35:07 rivo volumio[3464]: at Socket. (node:internal/child_process:457:11) Oct 03 01:35:07 rivo volumio[3464]: at Socket.emit (node:events:514:28) Oct 03 01:35:07 rivo volumio[3464]: at Pipe. (node:net:337:12) { Oct 03 01:35:07 rivo volumio[3464]: code: 1, Oct 03 01:35:07 rivo volumio[3464]: killed: false, Oct 03 01:35:07 rivo volumio[3464]: signal: null, Oct 03 01:35:07 rivo volumio[3464]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 03 01:35:07 rivo volumio[3464]: stdout: '', Oct 03 01:35:07 rivo volumio[3464]: stderr: 'Job for vtcs.service canceled.\n' Oct 03 01:35:07 rivo volumio[3464]: } Oct 03 01:35:07 rivo volumio[3464]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 01:35:07 rivo sudo[31365]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 01:35:07 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 01:35:07 rivo sudo[31373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 01:35:07 rivo sudo[31373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 01:35:07 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 01:35:07 rivo sudo[31344]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31346]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31302]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 01:35:07 rivo sudo[31302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 01:35:07 rivo sudo[31302]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 01:35:07 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 01:35:07 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 01:35:07 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:07 rivo qobuz-connect[31163]: 20261003 01:35:07.416 [31163.31163] INFO SampleApp: shat down connection on UNIX socket Oct 03 01:35:07 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 01:35:07 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:07 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 01:35:07 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 01:35:07 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 01:35:07 rivo sudo[31364]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31366]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31272]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31373]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:07 rivo sudo[31349]: pam_unix(sudo:session): session closed for user root Oct 03 01:35:08 rivo sudo[31409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 01:34' Oct 03 01:35:08 rivo sudo[31409]: 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="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"