Oct 12 11:42:18 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:18 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:18 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a5, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a7, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a9, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00ab, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00ad, ...) Oct 12 11:42:20 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00af, ...) Oct 12 11:42:21 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:21 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:21 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:23 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:25 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:25 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:25 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a5, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a7, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00a9, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00ab, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00ad, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4/char00af, ...) Oct 12 11:42:26 rivo bluealsa[3290]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a4, ...) Oct 12 11:42:28 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0, ...) Oct 12 11:42:28 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1, ...) Oct 12 11:42:28 rivo bluealsa[3290]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_38_8A_06_CF_E5_E7/service00a0/char00a1/desc00a3, ...) Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.249+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.214:46784 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.280+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.214:46784 @ 0x210eb10" latency=-3.459702ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.282+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.242313ms timeout=20s included=[] excluded=[] Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.283+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" Oct 12 11:42:31 rivo volumio[16083]: info: Received Get System Info Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:42:31 rivo volumio[16083]: info: Discovery: Getting this device information Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.290+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" name=Rivo Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.291+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" language=it Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.295+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" timezone=Europe/Rome Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.297+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" available=true connected=false macAddress= ip4Address= ip6Address= Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.311+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" available=true connected=true macAddress=70:4a:0e:aa:6c:76 ip4Address=192.168.1.103/24 ip6Address= ssid="tim ok" Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.311+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" setupComplete=true Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:42:31 rivo volumio[16083]: Invalid card number '5'. Oct 12 11:42:31 rivo volumio[16083]: amixer -c 5 info | grep "FIIO K13 R2R" Oct 12 11:42:31 rivo volumio[16083]: Invalid card number '5'. Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.421+02:00 level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=5 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.421+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" selectedOutputId=5 Oct 12 11:42:31 rivo volumio[16083]: info: Received Get System Info Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:42:31 rivo volumio[16083]: info: Discovery: Getting this device information Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.442+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" currentVersion=4.208 latestVersion=4.208 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.442+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.214:46784 @ 0x210eb10" status=UPDATE_STATUS_NONE progress=0 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.443+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" userId= Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.443+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" providers=3 Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:42:31 rivo volumio[16083]: info: Discovery: Getting this device information Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:42:31 rivo volumio[16083]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.214 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.976+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" plugins=30 Oct 12 11:42:31 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.983+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.983+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs= volume=0 Oct 12 11:42:31 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:31.985+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.182+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.214:46784 @ 0x210eb10" latency=14.917121ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.203+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.279+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=http://pushupdates.volumio.org duration=73.891389ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.374+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=170.91944ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.496+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://www.googleapis.com duration=292.7333ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.529+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://securetoken.googleapis.com duration=325.093345ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.612+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=407.492ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.672+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=468.381639ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.684+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://functions.volumio.cloud duration=479.136834ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.685+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://functions.volumio.cloud duration=479.816242ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.703+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://database.volumio.cloud duration=497.874803ms Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.715+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=509.975899ms Oct 12 11:42:33 rivo sudo[16757]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:42:33 rivo sudo[16757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:42:33 rivo sudo[16759]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:42:33 rivo sudo[16759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:42:33 rivo sudo[16757]: pam_unix(sudo:session): session closed for user root Oct 12 11:42:33 rivo sudo[16759]: pam_unix(sudo:session): session closed for user root Oct 12 11:42:33 rivo volumio[16083]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 12 11:42:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:33.843+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=https://google.com duration=639.307579ms Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:42:33 rivo volumio[16083]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:42:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:42:34 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:34.026+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=http://plugins.volumio.org duration=820.989253ms Oct 12 11:42:34 rivo sudo[16765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:42:34 rivo sudo[16765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:42:34 rivo sudo[16765]: pam_unix(sudo:session): session closed for user root Oct 12 11:42:34 rivo sudo[16767]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:42:34 rivo sudo[16767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:42:34 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:34.074+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:46784 @ 0x210eb10" latency=-5.384897ms timeout=10s endpoint=http://cddb.volumio.org duration=870.048993ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 11:42:34 rivo sudo[16767]: pam_unix(sudo:session): session closed for user root Oct 12 11:42:34 rivo volumio[16083]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:42:34 rivo volumio[16083]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:42:34 rivo volumio[16083]: info: Listing playlists Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:42:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:42:35 rivo volumio[16083]: info: CoreCommandRouter::volumioVolatilePlay Oct 12 11:42:35 rivo volumio[16083]: info: CoreStateMachine::volatilePlay Oct 12 11:42:35 rivo volumio[16083]: info: WARNING: No play method for volatile plugin undefined Oct 12 11:42:42 rivo volumio[16083]: info: VolumeController::SetAlsaVolume15 Oct 12 11:42:42 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:42 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:42 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.851+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.851+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs= volume=15 Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.854+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:42 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:42:42 rivo volumio[16083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:42:42 rivo volumio[16083]: info: VolumeController::SetAlsaVolume45 Oct 12 11:42:42 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:42 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:42 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:42 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.943+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.944+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs= volume=45 Oct 12 11:42:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:42.945+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:42 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:42:42 rivo volumio[16083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:42:50 rivo volumio[16083]: info: CoreCommandRouter::volumioRandom Oct 12 11:42:50 rivo volumio[16083]: info: CoreCommandRouter::writePlayerControls Oct 12 11:42:50 rivo volumio[16083]: info: CoreStateMachine::setRepeat true single false Oct 12 11:42:50 rivo volumio[16083]: info: WARNING: No repeat method for plugin undefined Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::volumioPrevious Oct 12 11:42:52 rivo volumio[16083]: info: WARNING: Cannot execute Action because no volatile plugin is defined Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::volumioGetQueue Oct 12 11:42:52 rivo volumio[16083]: info: CoreStateMachine::getQueue Oct 12 11:42:52 rivo volumio[16083]: info: CorePlayQueue::getQueue Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:42:52 rivo volumio[16083]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 11:42:52 rivo volumio[16083]: info: Received Get System Version Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 11:42:52 rivo volumio[16083]: info: Received Get System Info Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:42:52 rivo volumio[16083]: info: Discovery: Getting this device information Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:52 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::volumioPlay Oct 12 11:42:56 rivo volumio[16083]: verbose: UNSET VOLATILE: Service: undefined Oct 12 11:42:56 rivo volumio[16083]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Oct 12 11:42:56 rivo volumio[16083]: info: QobuzConnect: Received stop Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::play index 0 Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::stop Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::play index undefined Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:56 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:56 rivo volumio[16083]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:42:56 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:42:56 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:42:56 rivo volumio[16083]: info: [1791798176869] ControllerWebradio::clearAddPlayTrack Oct 12 11:42:56 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 11:42:56 rivo volumio[16083]: info: sendMpdCommand stop took 2 milliseconds Oct 12 11:42:56 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 11:42:56 rivo volumio[16083]: info: sendMpdCommand clear took 1 milliseconds Oct 12 11:42:56 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 12 11:42:56 rivo volumio[16083]: info: Oct 12 11:42:56 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:56 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:56 rivo volumio[16083]: info: Oct 12 11:42:56 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:56 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:56 rivo volumio[16083]: info: Oct 12 11:42:56 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:56 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 295 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 301ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 298ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 297ms Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand play took 14 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 14ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 7ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 5ms Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioPlay Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::play index 0 Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::stop Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::play index undefined Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:42:57 rivo volumio[16083]: info: [1791798177399] ControllerWebradio::clearAddPlayTrack Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand stop took 3 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:57 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:57 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand status took 12 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand clear took 13 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand status took 11 milliseconds Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:57 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:57 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 12 11:42:57 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:57.445+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=0 volume=45 Oct 12 11:42:57 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:57.445+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 46ms Oct 12 11:42:57 rivo volumio[16083]: error: updateQueue error: null Oct 12 11:42:57 rivo volumio[16083]: error: updateQueue error: null Oct 12 11:42:57 rivo volumio[16083]: error: updateQueue error: null Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand status took 46 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 45ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 44ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 42ms Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:57 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:57 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:57 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:57 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:57 rivo volumio[16083]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 12 11:42:57 rivo volumio[16083]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 11:42:57 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:57.480+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=0 volume=45 Oct 12 11:42:57 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:57.481+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 77ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 84ms Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 155 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 11:42:57 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: Oct 12 11:42:57 rivo volumio[16083]: ---------------------------- MPD announces system playlist update Oct 12 11:42:57 rivo volumio[16083]: info: Ignoring MPD Status Update Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 10ms Oct 12 11:42:57 rivo volumio[16083]: info: sendMpdCommand play took 7 milliseconds Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 6ms Oct 12 11:42:57 rivo volumio[16083]: info: ------------------------------ 4ms Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 31 milliseconds Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 46 milliseconds Oct 12 11:42:58 rivo volumio[16083]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 47 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand clearerror took 23 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 20 milliseconds Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.674+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1003 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 120ms Oct 12 11:42:58 rivo volumio[16083]: info: Oct 12 11:42:58 rivo volumio[16083]: ---------------------------- MPD announces state update: player Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::getState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 133 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand clearerror took 126 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand clearerror took 123 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 111 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 109 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand status took 47 milliseconds Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseState Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.799+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.799+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1342 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.800+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.801+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.801+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1342 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.802+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.803+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.803+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1342 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.804+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 258ms Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 257ms Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 226ms Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 87 milliseconds Oct 12 11:42:58 rivo volumio[16083]: info: sendMpdCommand playlistinfo took 84 milliseconds Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio[16083]: info: ControllerMpd::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::servicePushState Oct 12 11:42:58 rivo volumio[16083]: info: CorePlayQueue::getTrack 0 Oct 12 11:42:58 rivo volumio[16083]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 41303/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 12 11:42:58 rivo volumio[16083]: verbose: CURRENT POSITION 0 Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState stateService pause Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:42:58 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:42:58 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:42:58 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.877+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.877+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1342 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.878+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.879+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.879+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=1342 volume=45 Oct 12 11:42:58 rivo volumio5-onboarding[3918]: time=2026-10-12T11:42:58.880+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 281ms Oct 12 11:42:58 rivo volumio[16083]: info: ------------------------------ 193ms Oct 12 11:42:58 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:42:58 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:42:58 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:42:58 rivo volumio[16083]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:43:01 rivo volumio[16083]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:43:01 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:43:05 rivo volumio[16083]: Invalid card number '5'. Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 11:43:05 rivo volumio[16083]: info: Received Get System Info Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:43:05 rivo volumio[16083]: info: Discovery: Getting this device information Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:05 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:43:33 rivo volumio[16083]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 11:43:33 rivo volumio[16083]: info: Preparing to save Alsa Options, stopping services first Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:33 rivo volumio[16083]: info: Saving Audio Output to: {"output_device":{"value":"0,2","label":"S/PDIF + AES/EBU"}} Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:43:33 rivo volumio[16083]: info: Found match in Cards Database for ignoring default Mixer Oct 12 11:43:33 rivo volumio[16083]: info: Setting mixer Audio hdmi-out mute for card S/PDIF + AES/EBU Oct 12 11:43:33 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:33 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 11:43:33 rivo vtcs[16475]: [2026-10-12 11:43:33.865] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 12 11:43:33 rivo vtcs[16475]: [2026-10-12 11:43:33.866] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 11:43:33 rivo volumio[16083]: info: Ignoring Mixers Options Oct 12 11:43:33 rivo volumio[16083]: info: Device 0 does not have any Mixer Control Available Oct 12 11:43:33 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:33 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 11:43:33 rivo sudo[16905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:33 rivo sudo[16905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:33 rivo sudo[16907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:33 rivo sudo[16907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 11:43:33 rivo volumio[16083]: info: Updating Volume Controller Parameters: Device: 0,2 Name: S/PDIF + AES/EBU Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 11:43:33 rivo volumio[16083]: info: Disabling external Volume Control Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 11:43:33 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:43:33 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:33 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:43:33 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:43:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:33.965+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:43:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:33.965+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=36170 volume=100 Oct 12 11:43:33 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:33.966+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:43:33 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 11:43:33 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:33 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 11:43:33 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 11:43:34 rivo sudo[16905]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16907]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo sudo[16916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo sudo[16918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:34 rivo sudo[16925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:34 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:34 rivo volumio[16083]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:43:34 rivo sudo[16931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:34 rivo sudo[16931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16916]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16918]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:43:34 rivo sudo[16925]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16931]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo sudo[16939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:34 rivo sudo[16939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:34 rivo sudo[16940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: Asound.conf file written Oct 12 11:43:34 rivo sudo[16944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 11:43:34 rivo sudo[16944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:43:34 rivo qobuz-connect[16660]: 20261012 11:43:34.328 [16660.16660] INFO SampleApp: Stopping Local configuration server Oct 12 11:43:34 rivo sudo[16944]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 12 11:43:34 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 12 11:43:34 rivo kernel: out of value, fixed it Oct 12 11:43:34 rivo kernel: id=0 set inskew=0 Oct 12 11:43:34 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:34 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:34 rivo volumio[16083]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 11:43:34 rivo volumio[16083]: Hardware is initialized using a generic method Oct 12 11:43:34 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 12 11:43:34 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 12 11:43:34 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 12 11:43:34 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 12 11:43:34 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:34 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:34 rivo sudo[16949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:34 rivo sudo[16949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:34 rivo sudo[16949]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:34 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:34 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:34 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 11:43:34 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:34 rivo sudo[16960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo kernel: aml_spdif_open Oct 12 11:43:34 rivo sudo[16960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:34 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:34 rivo kernel: aml_spdif_close Oct 12 11:43:34 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:34 rivo sudo[16962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:34 rivo sudo[16962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16962]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[16967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:34 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:34 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo sudo[16967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:34 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:34 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:34 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:34 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:34 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:34 rivo kernel: aml_spdif_open Oct 12 11:43:34 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:34 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:34 rivo kernel: aml_spdif_close Oct 12 11:43:34 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:34 rivo sudo[16976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo sudo[16976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:34 rivo sudo[16979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo sudo[16979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:34 rivo sudo[16981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:34 rivo sudo[16981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo sudo[16998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:34 rivo sudo[16998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo sudo[17004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo sudo[17004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:34 rivo sudo[16960]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:34 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:34 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:34 rivo sudo[16998]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo sudo[17004]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo sudo[17020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:34 rivo sudo[17020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:34 rivo sudo[17021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:34 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:34 rivo sudo[17021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:34 rivo sudo[16981]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:34 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:34 rivo sudo[16979]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:35 rivo sudo[17035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[16976]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:35 rivo sudo[17042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:35 rivo sudo[17042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17035]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:35 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:35 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:35 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:35 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:35 rivo sudo[17052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:35 rivo sudo[17052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:35 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:35 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:35 rivo sudo[17054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:35 rivo sudo[17054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17042]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:35 rivo sudo[17059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:35 rivo sudo[17059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:35 rivo sudo[17061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:35 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:35 rivo sudo[17067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:35 rivo sudo[17067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:35 rivo sudo[17061]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17052]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:43:35 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:35 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:35 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:35 rivo volumio[16083]: info: Asound.conf file written Oct 12 11:43:35 rivo sudo[17073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:35 rivo kernel: aml_spdif_open Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:35 rivo kernel: aml_spdif_close Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: aml_spdif_open Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:35 rivo kernel: aml_spdif_close Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo sudo[17073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 11:43:35 rivo sudo[17076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17076]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17073]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17067]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:35 rivo sudo[17093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:35 rivo sudo[17093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 12 11:43:35 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 12 11:43:35 rivo kernel: out of value, fixed it Oct 12 11:43:35 rivo kernel: id=0 set inskew=0 Oct 12 11:43:35 rivo sudo[17059]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17054]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:35 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:35 rivo volumio[16083]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 11:43:35 rivo volumio[16083]: Hardware is initialized using a generic method Oct 12 11:43:35 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 12 11:43:35 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 12 11:43:35 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 12 11:43:35 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 12 11:43:35 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:35 rivo sudo[17094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:35 rivo sudo[17094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:35 rivo sudo[17100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:35 rivo sudo[17100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17100]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:43:35 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:43:35 rivo sudo[17103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:35 rivo sudo[17103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:35 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:35 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:35 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo sudo[17094]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo kernel: aml_spdif_open Oct 12 11:43:35 rivo sudo[17093]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:35 rivo kernel: aml_spdif_close Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: aml_spdif_open Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:35 rivo kernel: aml_spdif_close Oct 12 11:43:35 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:35 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:35 rivo sudo[17130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:35 rivo sudo[17137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:35 rivo sudo[17130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo sudo[17137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:35 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:35 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:35 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:35 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:35 rivo volumio[16083]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 11:43:35 rivo sudo[17144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:35 rivo qobuz-connect[16660]: 20261012 11:43:35.806 [16660.16660] INFO SampleApp: shat down connection on UNIX socket Oct 12 11:43:35 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:35 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:35 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:35 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:35 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:35 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:35 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:35 rivo sudo[17144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:35 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:35 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:43:35 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:35 rivo volumio[16083]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 11:43:35 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:35 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:35 rivo volumio[16083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 12 11:43:35 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:35 rivo sudo[17144]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:35 rivo volumio[16083]: 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 12 11:43:35 rivo sudo[16939]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[16940]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17021]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:35 rivo sudo[17020]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:36 rivo sudo[17157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo sudo[17159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:36 rivo sudo[17159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo volumio[16083]: info: Asound.conf file unchanged, so no further update is needed Oct 12 11:43:36 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:36 rivo sudo[17130]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17137]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:36 rivo sudo[17165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:36 rivo sudo[17165]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:36 rivo sudo[17168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:43:36 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:43:36 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:36 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:36 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:36 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:36 rivo kernel: aml_spdif_open Oct 12 11:43:36 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:36 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:36 rivo kernel: aml_spdif_close Oct 12 11:43:36 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:36 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:36 rivo sudo[17178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:36 rivo sudo[17178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo sudo[17157]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:43:36 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:43:36 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:36 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:36 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:36 rivo sudo[17184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:36 rivo sudo[17184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:36 rivo sudo[17189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:36 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:36 rivo sudo[17189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:36 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:36 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:36 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:36 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:36 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:36 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:36 rivo sudo[17159]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo volumio[16083]: info: Executing endpoint qc_getconfig Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:36 rivo sudo[17189]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:36 rivo sudo[17178]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:36 rivo sudo[17217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo sudo[17184]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo volumio[16083]: info: Executing endpoint qc_getconfig Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 11:43:36 rivo sudo[17228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:36 rivo sudo[17228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:43:36 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:36 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:43:36 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:36 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:36 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:36 rivo sudo[17217]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo sudo[17228]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:36 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:36 rivo sudo[17234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:36 rivo sudo[17234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:36 rivo sudo[17239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:36 rivo sudo[17239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:36 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:43:36 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:43:36 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:36 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:36 rivo sudo[17234]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:36 rivo volumio[16083]: info: Executing endpoint qc_getconfig Oct 12 11:43:36 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 11:43:37 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:43:37 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:43:37 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.038 [17232.17232] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 11:43:37 rivo volumio[16083]: info: Asound.conf file written Oct 12 11:43:37 rivo sudo[17246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:37 rivo sudo[17246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.048 [17232.17232] INFO VolumeManager: [0xaca86148]: Setting new playback volume: 75 Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.048 [17232.17232] INFO VolumeManager: [0xaca86148]: Setting new mute state: 0 Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.048 [17232.17232] INFO AudioStreamManager: [0xaca85ea0]: Setting new audio download buffer size: 1048576 Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.048 [17232.17232] INFO QobuzConnect: [0xaca86a10]: Client initialized! Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.048 [17232.17232] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 11:43:37 rivo sudo[17197]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 11:43:37 rivo sudo[17197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 11:43:37 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:37 rivo sudo[17239]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.103 [17232.17232] INFO LocalConfigManager: [0xaca85bc8]: Starting Local Configuration server Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.103 [17232.17232] INFO SampleApp: Starting Local configuration server Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.104 [17232.17232] INFO SampleApp: Connected to UNIX socket client 0xaca70908 Oct 12 11:43:37 rivo sudo[17197]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo sudo[17252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 11:43:37 rivo sudo[17252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo kernel: aml_spdif_open Oct 12 11:43:37 rivo sudo[17252]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:37 rivo kernel: aml_spdif_close Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: aml_spdif_open Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:37 rivo kernel: aml_spdif_close Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo sudo[17246]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.188 [17232.17232] INFO SampleApp: Playback volume changed: 75 Oct 12 11:43:37 rivo sudo[17282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:37 rivo sudo[17282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 12 11:43:37 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 12 11:43:37 rivo kernel: out of value, fixed it Oct 12 11:43:37 rivo kernel: id=0 set inskew=0 Oct 12 11:43:37 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:37 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:37 rivo volumio[16083]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 11:43:37 rivo volumio[16083]: Hardware is initialized using a generic method Oct 12 11:43:37 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 12 11:43:37 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 12 11:43:37 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 12 11:43:37 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 12 11:43:37 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:37 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:37 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:43:37 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:43:37 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:43:37 rivo sudo[17287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:37 rivo sudo[17290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:37 rivo sudo[17290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo sudo[17287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo sudo[17287]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:37 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:37 rivo sudo[17282]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo kernel: aml_spdif_open Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:37 rivo kernel: aml_spdif_close Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: aml_spdif_open Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:37 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:37 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:37 rivo kernel: aml_spdif_close Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo kernel: aml_spdif_open Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:37 rivo kernel: aml_spdif_close Oct 12 11:43:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:37 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:37 rivo sudo[17310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:37 rivo sudo[17310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo sudo[17313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:37 rivo sudo[17313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo volumio[16083]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 11:43:37 rivo volumio[16083]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 11:43:37 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:37 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:37 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:37 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:37 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:37 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:37 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:37 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:37 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:37 rivo sudo[17319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:37 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:37 rivo sudo[17319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:37 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:37 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:37 rivo sudo[17310]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:37 rivo sudo[17319]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:37 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:37 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:37 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:37 rivo sudo[17332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:37 rivo sudo[17332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo sudo[17313]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:37 rivo volumio[16083]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:37 rivo volumio[16083]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:37 rivo qobuz-connect[17232]: 20261012 11:43:37.801 [17232.17232] INFO SampleApp: Stopping Local configuration server Oct 12 11:43:37 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:43:37 rivo volumio[16083]: 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 12 11:43:37 rivo volumio[16083]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:37 rivo volumio[16083]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:37 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:37 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:37 rivo sudo[17351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:43:37 rivo sudo[17351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo volumio[16083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 12 11:43:37 rivo sudo[17355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:43:37 rivo sudo[17355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:37 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:37 rivo sudo[17356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:37 rivo sudo[17356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 11:43:37 rivo volumio[16083]: info: CoreStateMachine::getcurrentVolume Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 11:43:37 rivo volumio[16083]: info: CoreStateMachine::pushState Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:43:37 rivo volumio[16083]: info: CoreCommandRouter::volumioPushState Oct 12 11:43:37 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:43:38 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:38 rivo volumio[16083]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:43:38 rivo volumio[16083]: info: MRS: Pushing multiroomSync output Oct 12 11:43:38 rivo sudo[17351]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17355]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:38.030+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:43:38 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:38.031+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" state=STATUS_STOPPED positionMs=40361 volume=100 Oct 12 11:43:38 rivo volumio5-onboarding[3918]: time=2026-10-12T11:43:38.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:46784 @ 0x210eb10" id= title= Oct 12 11:43:38 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:43:38 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:38 rivo volumio[16083]: info: Asound.conf file written Oct 12 11:43:38 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:38 rivo sudo[17367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:38 rivo sudo[17367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:38 rivo sudo[17356]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 11:43:38 rivo sudo[17373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo sudo[17367]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17373]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:38 rivo sudo[17376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 12 11:43:38 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 12 11:43:38 rivo kernel: out of value, fixed it Oct 12 11:43:38 rivo kernel: id=0 set inskew=0 Oct 12 11:43:38 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:38 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:38 rivo volumio[16083]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 11:43:38 rivo volumio[16083]: Hardware is initialized using a generic method Oct 12 11:43:38 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 12 11:43:38 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 12 11:43:38 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 12 11:43:38 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 12 11:43:38 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:38 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:38 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:43:38 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:43:38 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:43:38 rivo sudo[17398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:38 rivo sudo[17398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo sudo[17395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:38 rivo sudo[17395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:38 rivo sudo[17395]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:38 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo sudo[17376]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17342]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 11:43:38 rivo kernel: aml_spdif_open Oct 12 11:43:38 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:38 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:38 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:38 rivo kernel: aml_spdif_close Oct 12 11:43:38 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:38 rivo kernel: aml_spdif_open Oct 12 11:43:38 rivo sudo[17342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 11:43:38 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:38 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:38 rivo kernel: aml_spdif_close Oct 12 11:43:38 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:38 rivo sudo[17342]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:38 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:38 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:38 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:38 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:38 rivo sudo[17418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:38 rivo sudo[17418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo sudo[17421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:38 rivo sudo[17421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:38 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:38 rivo sudo[17428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:38 rivo sudo[17428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:38 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:38 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:38 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:38 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:38 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:38 rivo volumio[16083]: info: Updating RAAT Signal Path Oct 12 11:43:38 rivo volumio[16083]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 11:43:38 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:38 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:38 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:38 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:38 rivo sudo[17428]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 11:43:38 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 11:43:38 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo sudo[17441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:38 rivo sudo[17441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:38 rivo sudo[17418]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo sudo[17421]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:38 rivo volumio[16083]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:38 rivo volumio[16083]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:38 rivo volumio[16083]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:38 rivo sudo[17460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:43:38 rivo sudo[17460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:38 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:43:38 rivo sudo[17462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:43:38 rivo sudo[17462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo volumio[16083]: info: Not Reporting Auto name since its the default one Oct 12 11:43:39 rivo volumio[16083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 12 11:43:39 rivo volumio[16083]: info: Starting Shairport Sync Oct 12 11:43:39 rivo volumio[16083]: info: Preparing to generate the ALSA configuration file Oct 12 11:43:39 rivo sudo[17469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:43:39 rivo sudo[17469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:43:39 rivo qobuz-connect[17232]: 20261012 11:43:39.116 [17232.17232] INFO SampleApp: shat down connection on UNIX socket Oct 12 11:43:39 rivo sudo[17462]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo sudo[17460]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:43:39 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:39 rivo volumio[16083]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:43:39 rivo volumio[16083]: info: Reading ALSA contributions from plugins. Oct 12 11:43:39 rivo volumio[16083]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 11:43:39 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:39 rivo sudo[17429]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 11:43:39 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:39 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:43:39 rivo sudo[17429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 11:43:39 rivo sudo[17478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:43:39 rivo sudo[17478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:39 rivo sudo[17332]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo sudo[17441]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo sudo[17429]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo volumio[16083]: info: Asound.conf file written Oct 12 11:43:39 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:43:39 rivo sudo[17478]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:43:39 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:39 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:43:39 rivo sudo[17469]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo sudo[17497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:43:39 rivo sudo[17497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo sudo[17496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 11:43:39 rivo sudo[17496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo sudo[17496]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 12 11:43:39 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 12 11:43:39 rivo kernel: out of value, fixed it Oct 12 11:43:39 rivo kernel: id=0 set inskew=0 Oct 12 11:43:39 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:39 rivo volumio[16083]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 11:43:39 rivo volumio[16083]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 11:43:39 rivo volumio[16083]: Hardware is initialized using a generic method Oct 12 11:43:39 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 12 11:43:39 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 12 11:43:39 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 12 11:43:39 rivo volumio[16083]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 12 11:43:39 rivo volumio[16083]: info: Output device has changed, restarting MPD Oct 12 11:43:39 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:43:39 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:43:39 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:43:39 rivo volumio[16083]: info: Output device has changed, restarting Shairport Sync Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:39 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:43:39 rivo sudo[17497]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo kernel: aml_spdif_open Oct 12 11:43:39 rivo sudo[17517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:43:39 rivo sudo[17517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:39 rivo kernel: aml_spdif_close Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo kernel: aml_spdif_open Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo sudo[17517]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:39 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:39 rivo kernel: aml_spdif_close Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo volumio[16083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:43:39 rivo volumio[16083]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:39 rivo volumio[16083]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo sudo[17532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:43:39 rivo sudo[17532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo kernel: aml_spdif_open Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo kernel: spdif_a keep clk continuous Oct 12 11:43:39 rivo kernel: aml_spdif_close Oct 12 11:43:39 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 12 11:43:39 rivo sudo[17556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:39 rivo sudo[17556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo sudo[17559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:43:39 rivo sudo[17559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:43:39 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:43:39 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:43:39 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:39 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:43:39 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:43:39 rivo volumio[16083]: info: MPD Permissions set Oct 12 11:43:39 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:39 rivo volumio[16083]: info: TidalConnect service stoped! Oct 12 11:43:39 rivo volumio[16083]: info: Raat Daemon started successfully Oct 12 11:43:39 rivo volumio[16083]: info: Shairport-Sync Started Oct 12 11:43:39 rivo volumio[16083]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:39 rivo volumio[16083]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:43:39 rivo sudo[17565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:43:39 rivo sudo[17565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo volumio[16083]: info: Executing endpoint qc_getconfig Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 11:43:39 rivo sudo[17572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:43:39 rivo sudo[17572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:39 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.934 [17479.17479] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 11:43:39 rivo volumio[16083]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 11:43:39 rivo volumio[16083]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 11:43:39 rivo volumio[16083]: info: Executing endpoint restartRAATSocket Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.946 [17479.17479] INFO VolumeManager: [0xab263148]: Setting new playback volume: 75 Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.947 [17479.17479] INFO VolumeManager: [0xab263148]: Setting new mute state: 0 Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.947 [17479.17479] INFO AudioStreamManager: [0xab262ea0]: Setting new audio download buffer size: 1048576 Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.947 [17479.17479] INFO QobuzConnect: [0xab263a10]: Client initialized! Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.947 [17479.17479] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 11:43:39 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.995 [17479.17479] INFO LocalConfigManager: [0xab262bc8]: Starting Local Configuration server Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.995 [17479.17479] INFO SampleApp: Starting Local configuration server Oct 12 11:43:39 rivo qobuz-connect[17479]: 20261012 11:43:39.996 [17479.17479] INFO SampleApp: Connected to UNIX socket client 0xab24d908 Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:43:40 rivo volumio[16083]: info: Executing endpoint tc_getconfig Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 12 11:43:40 rivo vtcs[17475]: STARTING TidalConnect services, version: 1.6.1 Oct 12 11:43:40 rivo sudo[17565]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:40 rivo volumio[16083]: info: RAAT: Requesting Headphone Status Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:43:40 rivo sudo[17556]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:40 rivo sudo[17559]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:40 rivo qobuz-connect[17479]: 20261012 11:43:40.084 [17479.17479] INFO SampleApp: Playback volume changed: 75 Oct 12 11:43:40 rivo sudo[17572]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:40 rivo volumio[16083]: info: CoreCommandRouter::volumioGetState Oct 12 11:43:40 rivo vtcs[17475]: STARTED TidalConnect services. Oct 12 11:43:40 rivo volumio[16083]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 12 11:43:40 rivo volumio[16083]: Job for vtcs.service canceled. Oct 12 11:43:40 rivo volumio[16083]: {"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 12 11:43:40 rivo volumio[16083]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:43:40 rivo sudo[17593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:43:40 rivo sudo[17593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:43:40 rivo volumio[16083]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 12 11:43:40 rivo volumio[16083]: Job for vtcs.service canceled. Oct 12 11:43:40 rivo volumio[16083]: at ChildProcess.exithandler (node:child_process:421:12) Oct 12 11:43:40 rivo volumio[16083]: at ChildProcess.emit (node:events:514:28) Oct 12 11:43:40 rivo volumio[16083]: at maybeClose (node:internal/child_process:1105:16) Oct 12 11:43:40 rivo volumio[16083]: at Socket. (node:internal/child_process:457:11) Oct 12 11:43:40 rivo volumio[16083]: at Socket.emit (node:events:514:28) Oct 12 11:43:40 rivo volumio[16083]: at Pipe. (node:net:337:12) { Oct 12 11:43:40 rivo volumio[16083]: code: 1, Oct 12 11:43:40 rivo volumio[16083]: killed: false, Oct 12 11:43:40 rivo volumio[16083]: signal: null, Oct 12 11:43:40 rivo volumio[16083]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 12 11:43:40 rivo volumio[16083]: stdout: '', Oct 12 11:43:40 rivo volumio[16083]: stderr: 'Job for vtcs.service canceled.\n' Oct 12 11:43:40 rivo volumio[16083]: } Oct 12 11:43:40 rivo volumio[16083]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:43:40 rivo qobuz-connect[17479]: 20261012 11:43:40.163 [17479.17479] INFO SampleApp: Stopping Local configuration server Oct 12 11:43:40 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:43:40 rivo sudo[17577]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 11:43:40 rivo sudo[17577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 11:43:40 rivo sudo[17577]: pam_unix(sudo:session): session closed for user root Oct 12 11:43:40 rivo sudo[17611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 11:42' Oct 12 11:43:40 rivo sudo[17611]: 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"