Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.136Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.116:35154 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.191Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.005975163s platform=PLATFORM_ANDROID version=6.260930.0 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.191Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.006406453s timeout=20s included=[] excluded=[] Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.191Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" Oct 12 10:05:40 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:40 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.196Z level=INFO msg="emitting device name changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" name=Rivoplus Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.198Z level=INFO msg="emitting device language changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" language=en Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.200Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" timezone=Europe/London Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.201Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" available=true connected=true macAddress=02:00:00:16:11:01 ip4Address=192.168.68.101/24 ip6Address= Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.203Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.204Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" setupComplete=true Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 10:05:40 rivoplus volumio[3547]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 12 10:05:40 rivoplus volumio[3547]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 12 10:05:40 rivoplus volumio[3547]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 12 10:05:40 rivoplus volumio[3547]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.338Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" selectedOutputId=5 Oct 12 10:05:40 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:40 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.368Z level=INFO msg="emitting software info changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" currentVersion=4.208 latestVersion=4.208 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.368Z level=INFO msg="emitting software update progress event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" status=UPDATE_STATUS_NONE progress=0 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.369Z level=INFO msg="emitting user changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" userId=vDzTlPJIOCOvp77ScXpTIdZ9IAG3 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.369Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" providers=9 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.369Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" plugins=28 Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.374Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" state=STATUS_PAUSED positionMs=292469 volume=100 Oct 12 10:05:40 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:40.374Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.116:35154 @ 0x1d0e4b0" id=qobuz://song/226634860 title="You And Your Friend" Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:40 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:40 rivoplus volumio[3547]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.116 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 10:05:40 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 10:05:41 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:41.434Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-995.157207ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 10:05:41 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:41.473Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s Oct 12 10:05:41 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:41.612Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=http://pushupdates.volumio.org duration=119.945862ms Oct 12 10:05:41 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:41.798Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=325.127029ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.028Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://securetoken.googleapis.com duration=551.234321ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.037Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=562.871785ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.147Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://www.googleapis.com duration=670.856558ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.151Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://google.com duration=678.002961ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.173Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=698.14896ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.189Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://functions.volumio.cloud duration=714.583652ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.190Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://functions.volumio.cloud duration=704.129233ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.202Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=729.382128ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.209Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=http://cddb.volumio.org duration=735.860237ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 10:05:42 rivoplus sudo[29027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 10:05:42 rivoplus sudo[29027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 10:05:42 rivoplus sudo[29029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 10:05:42 rivoplus sudo[29029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 10:05:42 rivoplus sudo[29027]: pam_unix(sudo:session): session closed for user root Oct 12 10:05:42 rivoplus sudo[29029]: pam_unix(sudo:session): session closed for user root Oct 12 10:05:42 rivoplus volumio[3547]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.116 UA: Mozilla/5.0 (Linux; Android 16; 25053PC47G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.107 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.351Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=http://plugins.volumio.org duration=877.387562ms Oct 12 10:05:42 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:42.420Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.116:35154 @ 0x1d0e4b0" latency=-1.00703329s timeout=10s endpoint=https://database.volumio.cloud duration=941.124946ms Oct 12 10:05:42 rivoplus sudo[29035]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 10:05:42 rivoplus sudo[29035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 10:05:42 rivoplus sudo[29037]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 10:05:42 rivoplus sudo[29035]: pam_unix(sudo:session): session closed for user root Oct 12 10:05:42 rivoplus sudo[29037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 10:05:42 rivoplus sudo[29037]: pam_unix(sudo:session): session closed for user root Oct 12 10:05:42 rivoplus volumio[3547]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.116 UA: Mozilla/5.0 (Linux; Android 16; 25053PC47G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.107 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 10:05:42 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C, ...) Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:42 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:42 rivoplus volumio[3547]: info: Listing playlists Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 10:05:42 rivoplus volumio[3547]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 10:05:42 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:42 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 10:05:42 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0001, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0001/char0002, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0001/char0004, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0001/char0006, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0001/char0008, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014/char0015, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014/char0017, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014/char0019, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014/char001b, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0014/char001b/desc001d, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0028, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0028/char0029, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0028/char002b, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service0028/char002b/desc002d, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009a, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009a/char009b, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009d, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009d/char009e, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009d/char009e/desc00a0, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009d/char00a1, ...) Oct 12 10:05:43 rivoplus bluealsa[3452]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_58_65_05_3F_3F_2C/service009d/char00a1/desc00a3, ...) Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 10:05:44 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:44.422Z level=INFO msg="new address was allocated" component=ble/conn old=19 new=20 Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 10:05:44 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:44 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:44 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:44 rivoplus dbus-daemon[2987]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.20" (uid=0 pid=4130 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3450 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 12 10:05:45 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 10:05:45 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:05:45 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:05:45 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:05:45 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:05:45 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:45 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:05:49 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::ClearQueue Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::stop Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::stPlaybackTimer Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::pushState Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 27 Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushState Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 27 Oct 12 10:05:49 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Oct 12 10:05:49 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::serviceStop Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 27 Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::serviceStop Oct 12 10:05:49 rivoplus volumio[3547]: info: [1791795949651] ControllerQobuz::stop Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 10:05:49 rivoplus volumio[3547]: info: ControllerMpd::stop Oct 12 10:05:49 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::clearPlayQueue Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:49 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:49 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:49 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/172443637 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/172443637 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/64868968 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/64868968 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/64868961 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/64868961 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/64868955 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/64868955 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/1744671 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/1744671 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/169149164 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/169149164 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20260076 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20260076 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/159431733 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/159431733 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/120751166 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/120751166 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/143351106 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/143351106 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/2390429 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/2390429 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/51579230 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/51579230 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/51579224 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/51579224 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/127259978 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/127259978 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/127259986 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/127259986 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/51579225 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/51579225 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/5049081 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/5049081 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/12159655 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/12159655 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/140425180 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/140425180 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/129937766 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/129937766 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/138285144 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/138285144 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/23530722 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/23530722 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/130369515 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/130369515 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/82311526 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/82311526 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/129342726 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/129342726 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/129342728 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/129342728 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/126836264 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/126836264 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/41723744 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/41723744 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/41723753 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/41723753 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/51894249 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/51894249 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/51894247 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/51894247 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/3809374 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/3809374 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/8630850 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/8630850 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27223282 Oct 12 10:05:49 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27223282 in service qobuz Oct 12 10:05:49 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:49 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:49.763Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 12 10:05:49 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:49.764Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" id=qobuz://song/226634861 title="On Every Street" Oct 12 10:05:50 rivoplus volumio[3547]: info: Updating RAAT Signal Path Oct 12 10:05:50 rivoplus volumio[3547]: info: Oct 12 10:05:50 rivoplus volumio[3547]: ---------------------------- MPD announces state update: player Oct 12 10:05:50 rivoplus volumio[3547]: info: sendMpdCommand stop took 534 milliseconds Oct 12 10:05:50 rivoplus volumio[3547]: info: ControllerMpd::getState Oct 12 10:05:50 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand status Oct 12 10:05:50 rivoplus volumio[3547]: info: sendMpdCommand status took 6 milliseconds Oct 12 10:05:50 rivoplus volumio[3547]: verbose: ControllerMpd::parseState Oct 12 10:05:50 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 10:05:50 rivoplus volumio[3547]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 12 10:05:50 rivoplus volumio[3547]: verbose: ControllerMpd::parseTrackInfo Oct 12 10:05:50 rivoplus volumio[3547]: info: ControllerMpd::pushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::servicePushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreStateMachine::pushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output Oct 12 10:05:50 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 27 Oct 12 10:05:50 rivoplus volumio[3547]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1326154&eid=226634861&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791661972&hmac=BqreASJpmPJhXTKSjP2S1HaVLPM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=226634861&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791661972&hmac=BqreASJpmPJhXTKSjP2S1HaVLPM","trackType":"qobuz"} Oct 12 10:05:50 rivoplus volumio[3547]: verbose: CURRENT POSITION 27 Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreStateMachine::syncState stateService stop Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreStateMachine::syncState currentStatus stop Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreStateMachine::pushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output Oct 12 10:05:50 rivoplus volumio[3547]: info: No code Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreStateMachine::pushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushState Oct 12 10:05:50 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Oct 12 10:05:50 rivoplus volumio[3547]: info: MRS: Pushing multiroomSync output Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.322Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" state=STATUS_PAUSED positionMs=0 volume=100 Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.323Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" id=qobuz://song/226634860 title="You And Your Friend" Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.323Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" state=STATUS_PAUSED positionMs=0 volume=100 Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.323Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" state=STATUS_PAUSED positionMs=0 volume=100 Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.323Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" id=qobuz://song/226634860 title="You And Your Friend" Oct 12 10:05:50 rivoplus volumio5-onboarding[4130]: time=2026-10-12T09:05:50.323Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.116:35154,00:00:00:00:00:00%13 @ 0x1d0e4b0" id=qobuz://song/226634860 title="You And Your Friend" Oct 12 10:05:50 rivoplus volumio[3547]: info: ------------------------------ 233ms Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1887 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1876 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1876 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1878 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1878 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1880 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1876 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1876 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1874 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1873 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1880 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1880 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1882 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1883 milliseconds Oct 12 10:05:51 rivoplus volumio[3547]: info: explodeUri took 1884 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: Updating RAAT Signal Path Oct 12 10:05:52 rivoplus volumio[3547]: info: Updating RAAT Signal Path Oct 12 10:05:52 rivoplus volumio[3547]: info: Updating RAAT Signal Path Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2694 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2696 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2695 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2697 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2699 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2700 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2695 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2697 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2698 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2699 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2701 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2701 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2731 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2893 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2895 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 2909 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 3003 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 3019 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: explodeUri took 3083 milliseconds Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:52 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:52 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPlay Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreStateMachine::play index 33 Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:52 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:52 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:52 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/52369837 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/52369837 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/392202587 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/392202587 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/114841432 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/114841432 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16503214 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16503214 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16503211 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16503211 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27124801 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27124801 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311763 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311763 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311772 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311772 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311822 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311822 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/61878330 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/61878330 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/61878325 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/61878325 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/54716283 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/54716283 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16012389 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16012389 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/10473666 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/10473666 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/10473664 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/10473664 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/10473671 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/10473671 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/10473665 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/10473665 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/41062551 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/41062551 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/59672924 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/59672924 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/114841421 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/114841421 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/114841415 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/114841415 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/10724111 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/10724111 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/15644435 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/15644435 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/15644421 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/15644421 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/15644429 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/15644429 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/107316569 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/107316569 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/385966644 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/385966644 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20108950 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20108950 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/12861114 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/12861114 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/63333702 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/63333702 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/30477127 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/30477127 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/9390518 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/9390518 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/2004516 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/2004516 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/14568644 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/14568644 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/96768890 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/96768890 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/238913589 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/238913589 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/15166686 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/15166686 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/15166684 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/15166684 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/5250679 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/5250679 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16503394 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16503394 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524798 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524798 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/7664854 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/7664854 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/4304523 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/4304523 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/23265470 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/23265470 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20750958 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20750958 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/39018216 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/39018216 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/29945090 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/29945090 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/9140031 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/9140031 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/7400 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/7400 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/301413415 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/301413415 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:52 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/385545485 Oct 12 10:05:52 rivoplus volumio[3547]: info: Exploding uri qobuz://song/385545485 in service qobuz Oct 12 10:05:52 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/18038127 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/18038127 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27865200 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27865200 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/35549898 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/35549898 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311743 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311743 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16012375 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16012375 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/17219987 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/17219987 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20260080 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20260080 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20260077 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20260077 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20260075 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20260075 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20260074 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20260074 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524816 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524816 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/16014972 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/16014972 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/20750953 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/20750953 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/50762818 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/50762818 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/53355674 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/53355674 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311742 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311742 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311741 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311741 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524790 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524790 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524791 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524791 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524808 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524808 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27524813 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/27524813 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/61878334 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/61878334 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/17307101 Oct 12 10:05:53 rivoplus volumio[3547]: info: Exploding uri qobuz://song/17307101 in service qobuz Oct 12 10:05:53 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreStateMachine::stop Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreStateMachine::play index undefined Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:53 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 33 Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreStateMachine::startPlaybackTimer Oct 12 10:05:53 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 33 Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:05:53 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 10:05:53 rivoplus volumio[3547]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Oct 12 10:05:53 rivoplus volumio[3547]: info: [1791795953049] ControllerQobuz::clearAddPlayTrack Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 603 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 587 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 609 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 604 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 602 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 602 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 616 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 632 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 595 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 599 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 571 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 597 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 573 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 600 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 615 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 591 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 611 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 601 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 588 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 588 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 642 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 606 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 640 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 595 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 618 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 643 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 620 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 614 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:53 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/12861114 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 656 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 655 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 625 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 654 milliseconds Oct 12 10:05:53 rivoplus volumio[3547]: info: explodeUri took 628 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2311 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2313 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2315 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2319 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2321 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2324 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2328 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2331 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2334 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2337 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2341 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2343 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2346 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:55 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/29945090 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2349 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2347 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2349 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2352 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2356 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2360 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2363 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2387 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2389 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2392 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2396 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2398 milliseconds Oct 12 10:05:55 rivoplus volumio[3547]: info: explodeUri took 2405 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3349 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::ClearQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::stop Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::clearPlayQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:56 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:56 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/27223282 Oct 12 10:05:56 rivoplus volumio[3547]: info: Using cached record of: qobuz://song/27223282 Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 33 Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPlay Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::play index 0 Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::stop Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::play index undefined Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 0 Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::startPlaybackTimer Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::getTrack 0 Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 10:05:56 rivoplus volumio[3547]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Oct 12 10:05:56 rivoplus volumio[3547]: info: [1791795956277] ControllerQobuz::clearAddPlayTrack Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3289 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3289 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3290 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3293 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3295 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3297 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3298 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3299 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3300 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3301 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3323 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3350 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3355 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: explodeUri took 3421 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:56 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:56 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:56 rivoplus volumio[3547]: info: getStreamUrl took 199 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 10:05:56 rivoplus volumio[3547]: info: getStreamUrl took 3441 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 10:05:56 rivoplus volumio[3547]: info: sendMpdCommand stop took 15 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: sendMpdCommand stop took 1 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 10:05:56 rivoplus volumio[3547]: info: Oct 12 10:05:56 rivoplus volumio[3547]: ---------------------------- MPD announces system playlist update Oct 12 10:05:56 rivoplus volumio[3547]: info: Ignoring MPD Status Update Oct 12 10:05:56 rivoplus volumio[3547]: info: Oct 12 10:05:56 rivoplus volumio[3547]: ---------------------------- MPD announces system playlist update Oct 12 10:05:56 rivoplus volumio[3547]: info: Ignoring MPD Status Update Oct 12 10:05:56 rivoplus volumio[3547]: info: sendMpdCommand clear took 2 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: info: sendMpdCommand clear took 3 milliseconds Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" Oct 12 10:05:56 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" Oct 12 10:05:56 rivoplus volumio[3547]: error: updateQueue error: null Oct 12 10:05:56 rivoplus volumio[3547]: error: updateQueue error: null Oct 12 10:05:56 rivoplus volumio[3547]: info: ------------------------------ 6ms Oct 12 10:05:56 rivoplus volumio[3547]: info: ------------------------------ 6ms Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/194467340 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/194467340 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/47683849 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/47683849 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/54091881 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/54091881 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/203636904 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/203636904 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/199955511 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/199955511 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/740353 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/740353 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/53764385 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/53764385 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/218888058 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/218888058 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/30369443 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/30369443 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:57 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:57 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:57 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/47683565 Oct 12 10:05:57 rivoplus volumio[3547]: info: Exploding uri qobuz://song/47683565 in service qobuz Oct 12 10:05:57 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" Oct 12 10:05:58 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/19884969 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/19884969 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311853 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311853 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/61807817 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/61807817 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/194467340 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/194467340 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/129342731 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/129342731 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/19884969 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/19884969 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/54091881 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/54091881 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/218888058 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/218888058 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/203636904 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/203636904 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/45149345 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/45149345 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/307648 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/307648 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/199955511 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/199955511 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/740353 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/740353 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/47683565 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/47683565 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/53764385 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/53764385 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25311853 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25311853 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/47683849 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/47683849 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/30369626 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/30369626 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25316308 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25316308 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: Oct 12 10:05:58 rivoplus volumio[3547]: ---------------------------- MPD announces system playlist update Oct 12 10:05:58 rivoplus volumio[3547]: info: Ignoring MPD Status Update Oct 12 10:05:58 rivoplus volumio[3547]: info: Oct 12 10:05:58 rivoplus volumio[3547]: ---------------------------- MPD announces system playlist update Oct 12 10:05:58 rivoplus volumio[3547]: info: Ignoring MPD Status Update Oct 12 10:05:58 rivoplus volumio[3547]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" took 207 milliseconds Oct 12 10:05:58 rivoplus volumio[3547]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1326154&eid=27223282&fmt=7&profile=raw&app_id=539451548&cid=4400661&etsp=1791799556&hmac=T5le3XCGjzFMiEhTgWXpfhtzQ5E" took 208 milliseconds Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 10:05:58 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand play Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 10:05:58 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand play Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/45149345 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/45149345 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/25316308 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/25316308 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/129342731 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/129342731 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/30369626 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/30369626 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:58 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:58 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:58 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/307648 Oct 12 10:05:58 rivoplus volumio[3547]: info: Exploding uri qobuz://song/307648 in service qobuz Oct 12 10:05:58 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:59 rivoplus volumio[3547]: info: ------------------------------ 118ms Oct 12 10:05:59 rivoplus volumio[3547]: info: ------------------------------ 118ms Oct 12 10:05:59 rivoplus volumio[3547]: info: sendMpdCommand play took 117 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: sendMpdCommand play took 116 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:59 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/61807817 Oct 12 10:05:59 rivoplus volumio[3547]: info: Exploding uri qobuz://song/61807817 in service qobuz Oct 12 10:05:59 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:59 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::getQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioAddQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::addQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::addQueueItems Oct 12 10:05:59 rivoplus volumio[3547]: info: Preload queue cleared Oct 12 10:05:59 rivoplus volumio[3547]: info: Adding Item to queue: qobuz://song/30369443 Oct 12 10:05:59 rivoplus volumio[3547]: info: Exploding uri qobuz://song/30369443 in service qobuz Oct 12 10:05:59 rivoplus volumio[3547]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1217 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1213 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1227 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1325 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1325 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/54091881 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1280 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1349 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1398 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1384 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1449 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/199955511 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 840 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 842 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 819 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 933 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 950 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 954 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1007 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1104 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/45149345 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:05:59 rivoplus volumio[3547]: info: explodeUri took 1235 milliseconds Oct 12 10:05:59 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1240 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1251 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1334 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/54091881 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1309 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/199955511 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1319 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1511 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1348 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1435 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1463 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1508 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: error: Commandrouter: Cannot explode uri qobuz://song/45149345 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1533 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1772 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1779 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1680 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: explodeUri took 1795 milliseconds Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:00 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:00 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 10:06:01 rivoplus volumio[3547]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 10:06:01 rivoplus volumio[3547]: info: Received Get System Version Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 10:06:01 rivoplus volumio[3547]: info: Received Get System Info Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:06:01 rivoplus volumio[3547]: info: Discovery: Getting this device information Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::volumioGetState Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:06:01 rivoplus volumio[3547]: info: explodeUri took 2916 milliseconds Oct 12 10:06:01 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:02 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:02 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:02 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:02 rivoplus volumio[3547]: info: explodeUri took 2963 milliseconds Oct 12 10:06:02 rivoplus volumio[3547]: info: CoreCommandRouter::volumioPushQueue Oct 12 10:06:02 rivoplus volumio[3547]: info: CorePlayQueue::saveQueue Oct 12 10:06:02 rivoplus volumio[3547]: info: CoreStateMachine::updateTrackBlock Oct 12 10:06:02 rivoplus volumio[3547]: info: CorePlayQueue::getTrackBlock Oct 12 10:06:02 rivoplus volumio[3547]: info: Oct 12 10:06:02 rivoplus volumio[3547]: ---------------------------- MPD announces state update: player Oct 12 10:06:02 rivoplus volumio[3547]: info: ControllerMpd::getState Oct 12 10:06:02 rivoplus volumio[3547]: verbose: ControllerMpd::sendMpdCommand status Oct 12 10:06:02 rivoplus volumio[3547]: info: All cached search sources collected Oct 12 10:06:02 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 12 10:06:02 rivoplus volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 12 10:06:02 rivoplus volumio[3547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 10:06:02 rivoplus volumio[3547]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 12 10:06:02 rivoplus volumio[3547]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 12 10:06:02 rivoplus volumio[3547]: at new NodeError (node:internal/errors:405:5) Oct 12 10:06:02 rivoplus volumio[3547]: at assert (node:internal/assert:14:11) Oct 12 10:06:02 rivoplus volumio[3547]: at internalConnectMultiple (node:net:1118:3) Oct 12 10:06:02 rivoplus volumio[3547]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 12 10:06:02 rivoplus volumio[3547]: at listOnTimeout (node:internal/timers:575:11) Oct 12 10:06:02 rivoplus volumio[3547]: at process.processTimers (node:internal/timers:514:7) { Oct 12 10:06:02 rivoplus volumio[3547]: code: 'ERR_INTERNAL_ASSERTION' Oct 12 10:06:02 rivoplus volumio[3547]: } Oct 12 10:06:02 rivoplus volumio[3547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 10:06:03 rivoplus sudo[29094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 10:05' Oct 12 10:06:03 rivoplus sudo[29094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"