-- Logs begin at Thu 2025-12-11 10:07:40 PST, end at Mon 2026-01-12 19:50:23 PST. -- Jan 12 19:49:00 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:00.188-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:00 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:00.489-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:00 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:00.790-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.091-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.392-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.693-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.994-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.994-08:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=20 chunks=1 index=0 Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.995-08:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%14 @ 0x3351ce0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Jan 12 19:49:01 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:01.995-08:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x355e6f0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.295-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.556-08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=10.0.0.32:56947 Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.595-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.617-08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="10.0.0.32:56947 @ 0x31964e0" latency=17.946108ms platform=PLATFORM_IOS version=4.251222.0 Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.617-08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="10.0.0.32:56947 @ 0x31964e0" latency=17.615448ms timeout=10s Jan 12 19:49:02 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:02 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 12 19:49:02 rivo volumio[3285]: No protocol specified Jan 12 19:49:02 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:49:02 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 19:49:02 rivo kernel: aml_spdif_open Jan 12 19:49:02 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 19:49:02 rivo kernel: spdif_a keep clk continuous Jan 12 19:49:02 rivo kernel: aml_spdif_close Jan 12 19:49:02 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 19:49:02 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:02.896-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:02 rivo volumio[3285]: Invalid card number. Jan 12 19:49:02 rivo volumio[3285]: No protocol specified Jan 12 19:49:02 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:02 rivo volumio[3285]: amixer -c 5 info | grep "miniDSP Flex 8" Jan 12 19:49:03 rivo volumio[3285]: No protocol specified Jan 12 19:49:03 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:03 rivo volumio[3285]: Card hw:5 'm8'/'miniDSP miniDSP Flex 8 at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 12 19:49:03 rivo volumio[3285]: No protocol specified Jan 12 19:49:03 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:03 rivo volumio[3285]: amixer -c 5 info | grep "miniDSP Flex 8" Jan 12 19:49:03 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:03.197-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:03 rivo volumio[3285]: No protocol specified Jan 12 19:49:03 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:03 rivo volumio[3285]: Card hw:5 'm8'/'miniDSP miniDSP Flex 8 at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:03 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:03 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:03 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:03 rivo volumio[3285]: verbose: New Socket.io Connection to 10.0.0.31:3000 from 10.0.0.32 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 19:49:03 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 19:49:03 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:03.498-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:03 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:03.799-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:04 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:04.100-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:04 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:04.402-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:04 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:04.702-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.003-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.304-08:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=20 Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.304-08:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=20 chunks=1 index=0 Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.305-08:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%14 @ 0x3351ce0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.305-08:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x355e6f0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.312-08:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=10.0.0.32:56947 error="read tcp 10.0.0.31:7331->10.0.0.32:56947: read: connection reset by peer" Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.312-08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=10.0.0.32:56947 Jan 12 19:49:05 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:05.312-08:00 level=INFO msg="connection to address closed" component=conn/multi addr=10.0.0.32:56947 Jan 12 19:49:09 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:09.380-08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=10.0.0.32:57108 Jan 12 19:49:09 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:09.436-08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="10.0.0.32:57108 @ 0x3000a50" latency=15.026954ms platform=PLATFORM_IOS version=4.251222.0 Jan 12 19:49:09 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:09.440-08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="10.0.0.32:57108 @ 0x3000a50" latency=18.952658ms timeout=10s Jan 12 19:49:09 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:09 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 12 19:49:09 rivo volumio[3285]: No protocol specified Jan 12 19:49:09 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:49:09 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 19:49:09 rivo kernel: aml_spdif_open Jan 12 19:49:09 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 19:49:09 rivo kernel: spdif_a keep clk continuous Jan 12 19:49:09 rivo kernel: aml_spdif_close Jan 12 19:49:09 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 19:49:09 rivo volumio[3285]: Invalid card number. Jan 12 19:49:09 rivo volumio[3285]: No protocol specified Jan 12 19:49:09 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:09 rivo volumio[3285]: amixer -c 5 info | grep "miniDSP Flex 8" Jan 12 19:49:09 rivo volumio[3285]: No protocol specified Jan 12 19:49:09 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:09 rivo volumio[3285]: Card hw:5 'm8'/'miniDSP miniDSP Flex 8 at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 12 19:49:09 rivo volumio[3285]: No protocol specified Jan 12 19:49:09 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:09 rivo volumio[3285]: amixer -c 5 info | grep "miniDSP Flex 8" Jan 12 19:49:10 rivo volumio[3285]: No protocol specified Jan 12 19:49:10 rivo volumio[3285]: xcb_connection_has_error() returned true Jan 12 19:49:10 rivo volumio[3285]: Card hw:5 'm8'/'miniDSP miniDSP Flex 8 at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:10 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:10 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:10 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:10 rivo volumio[3285]: verbose: New Socket.io Connection to 10.0.0.31:3000 from 10.0.0.32 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 19:49:10 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 19:49:15 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:15.800-08:00 level=INFO msg="new address was allocated" component=conn/ble old=23 new=24 Jan 12 19:49:16 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:16 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:16.250-08:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="10.0.0.32:57108 @ 0x3000a50" latency=17.525439ms timeout=10s from=APP_PAGE_ROOT Jan 12 19:49:16 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:16 rivo sudo[23537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 19:49:16 rivo sudo[23537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:49:16 rivo sudo[23537]: pam_unix(sudo:session): session closed for user root Jan 12 19:49:16 rivo sudo[23540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:49:16 rivo sudo[23540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:49:16 rivo sudo[23540]: pam_unix(sudo:session): session closed for user root Jan 12 19:49:16 rivo volumio[3285]: verbose: New Socket.io Connection to 10.0.0.31 from 10.0.0.32 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 19:49:16 rivo volumio[3285]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 12 19:49:16 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:16 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:17 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:17 rivo volumio[3285]: info: Listing playlists Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 19:49:17 rivo sudo[23545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 19:49:17 rivo sudo[23545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:49:17 rivo sudo[23545]: pam_unix(sudo:session): session closed for user root Jan 12 19:49:17 rivo sudo[23548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:49:17 rivo sudo[23548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:49:17 rivo sudo[23548]: pam_unix(sudo:session): session closed for user root Jan 12 19:49:17 rivo volumio[3285]: verbose: New Socket.io Connection to 10.0.0.31 from 10.0.0.32 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetQueue Jan 12 19:49:17 rivo volumio[3285]: info: CoreStateMachine::getQueue Jan 12 19:49:17 rivo volumio[3285]: info: CorePlayQueue::getQueue Jan 12 19:49:17 rivo volumio[3285]: info: Listing playlists Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 19:49:17 rivo volumio[3285]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 12 19:49:17 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:17 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 19:49:17 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 19:49:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 19:49:19 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:19 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 19:49:19 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:19 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:19 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:20 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:20.892-08:00 level=INFO msg="new address was allocated" component=conn/ble old=24 new=25 Jan 12 19:49:21 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:21 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:24 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:24.920-08:00 level=INFO msg="new address was allocated" component=conn/ble old=25 new=26 Jan 12 19:49:25 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:25 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:27 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 19:49:36 rivo volumio[3285]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 19:49:36 rivo volumio[3285]: info: Received Get System Version Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 19:49:36 rivo volumio[3285]: info: Received Get System Info Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 19:49:36 rivo volumio[3285]: info: Discovery: Getting this device information Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:36 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 19:49:38 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:38 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:38 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:49:38 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:38 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:38 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:38 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:38 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:38 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:38 rivo vtcs[4104]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jan 12 19:49:39 rivo volumio5-onboarding[4332]: time=2026-01-12T19:49:39.005-08:00 level=INFO msg="new address was allocated" component=conn/ble old=26 new=27 Jan 12 19:49:39 rivo dbus-daemon[2866]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.48" (uid=0 pid=4332 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:39 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:39 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:39 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:39 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:39 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:39 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:39 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:39 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:44 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:44 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:44 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:49:44 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:44 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:44 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:44 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:44 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:44 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:44 rivo vtcs[4104]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:45 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:45 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:45 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::servicePushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreStateMachine::pushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioPushState Jan 12 19:49:45 rivo volumio[3285]: info: CoreCommandRouter::volumioGetState Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output update for this device Jan 12 19:49:45 rivo volumio[3285]: info: MRS: Pushing multiroomSync output Jan 12 19:49:45 rivo volumio[3285]: info: Signalling Playback active due to playback status change Jan 12 19:49:45 rivo volumio[3285]: info: Updating RAAT Signal Path Jan 12 19:49:55 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:49:55 rivo volumio[3285]: info: CURURI: music-library Jan 12 19:49:55 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:49:56 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:49:56 rivo volumio[3285]: info: CURURI: music-library/USB Jan 12 19:49:57 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:49:58 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:49:58 rivo volumio[3285]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 19:49:58 rivo volumio[3285]: info: CoreStateMachine::ClearQueue Jan 12 19:49:58 rivo volumio[3285]: info: CoreStateMachine::stop Jan 12 19:49:58 rivo volumio[3285]: info: CoreStateMachine::serviceStop Jan 12 19:49:58 rivo volumio[3285]: info: CoreCommandRouter::serviceStop Jan 12 19:49:58 rivo volumio[3285]: info: CorePlayQueue::clearPlayQueue Jan 12 19:49:58 rivo volumio[3285]: info: CorePlayQueue::saveQueue Jan 12 19:49:58 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Jan 12 19:49:58 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Jan 12 19:49:58 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Jan 12 19:49:58 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:49:58 rivo volumio[3285]: info: Adding Item to queue: music-library/USB/Samsung_T52 Jan 12 19:49:58 rivo volumio[3285]: info: Exploding uri music-library/USB/Samsung_T52 in service mpd Jan 12 19:49:58 rivo vtcs[4104]: [close:85] Entering Jan 12 19:49:58 rivo vtcs[4104]: [feedThread:276] Exiting Jan 12 19:49:58 rivo vtcs[4104]: [close:100] Exiting Jan 12 19:49:58 rivo vtcs[4104]: [2026-01-12 19:49:58.661] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:0 Jan 12 19:50:14 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:50:14 rivo volumio[3285]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 19:50:14 rivo volumio[3285]: info: CoreStateMachine::ClearQueue Jan 12 19:50:14 rivo volumio[3285]: info: CoreStateMachine::stop Jan 12 19:50:14 rivo volumio[3285]: info: CoreStateMachine::serviceStop Jan 12 19:50:14 rivo volumio[3285]: info: CoreCommandRouter::serviceStop Jan 12 19:50:14 rivo volumio[3285]: info: CorePlayQueue::clearPlayQueue Jan 12 19:50:14 rivo volumio[3285]: info: CorePlayQueue::saveQueue Jan 12 19:50:14 rivo vtcs[4104]: [2026-01-12 19:50:14.598] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 12 19:50:14 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Jan 12 19:50:14 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Jan 12 19:50:14 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Jan 12 19:50:14 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:50:14 rivo volumio[3285]: info: Adding Item to queue: music-library/USB/Samsung_T52 Jan 12 19:50:14 rivo volumio[3285]: info: Exploding uri music-library/USB/Samsung_T52 in service mpd Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:18 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:18 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:18 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:18 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:18 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 19:50:18 rivo volumio[3285]: info: CoreStateMachine::ClearQueue Jan 12 19:50:18 rivo volumio[3285]: info: CoreStateMachine::stop Jan 12 19:50:18 rivo volumio[3285]: info: CoreStateMachine::serviceStop Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::serviceStop Jan 12 19:50:18 rivo volumio[3285]: info: CorePlayQueue::clearPlayQueue Jan 12 19:50:18 rivo volumio[3285]: info: CorePlayQueue::saveQueue Jan 12 19:50:18 rivo volumio[3285]: info: CoreCommandRouter::volumioPushQueue Jan 12 19:50:18 rivo vtcs[4104]: [2026-01-12 19:50:18.584] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 12 19:50:18 rivo volumio[3285]: info: CoreStateMachine::addQueueItems Jan 12 19:50:18 rivo volumio[3285]: info: CorePlayQueue::addQueueItems Jan 12 19:50:18 rivo volumio[3285]: info: Preload queue cleared Jan 12 19:50:18 rivo volumio[3285]: info: Adding Item to queue: music-library/USB/Samsung_T52 Jan 12 19:50:18 rivo volumio[3285]: info: Exploding uri music-library/USB/Samsung_T52 in service mpd Jan 12 19:50:22 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:22 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:22 rivo volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 19:50:22 rivo volumio[3285]: info: CURURI: music-library/USB/Samsung_T52 Jan 12 19:50:22 rivo volumio[3285]: info: PlaybackInactive Jan 12 19:50:22 rivo volumio[3285]: info: Device Session is_active: true Jan 12 19:50:22 rivo volumio[3285]: info: DeviceInactive Jan 12 19:50:22 rivo volumio[3285]: info: Relinquishing Volumio State Jan 12 19:50:22 rivo volumio[3285]: verbose: UNSET VOLATILE: Service: tidalconnect Jan 12 19:50:22 rivo volumio[3285]: info: unSetVolatile called Jan 12 19:50:22 rivo volumio[3285]: info: Relinquishing Volumio State to another service Jan 12 19:50:22 rivo vtcs[4104]: [2026-01-12 19:50:22.631] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 12 19:50:22 rivo volumio[3285]: info: ALBUMART /albumart?cacheid=744&web=Patricia%20Barber/Monday%20Night%3A%20Recorded%20Live%20At%20The%20Green%20Mill%20-%20Chicago%2C%20IL%20%202%2F20%2F06/extralarge&path=%2Fmnt%2FUSB%2FSamsung_T52%2F%24RECYCLE.BIN%2F%24R00B9TQ.zip&metadata=false Jan 12 19:50:22 rivo volumio[3285]: info: URI /mnt/USB/Samsung_T52/$RECYCLE.BIN/$R00B9TQ.zip Jan 12 19:50:22 rivo volumio[3285]: info: ALBUMART /albumart?cacheid=744&web=Patricia%20Barber/Monday%20Night%3A%20Recorded%20Live%20At%20The%20Green%20Mill%20-%20Chicago%2C%20IL%20%202%2F20%2F06/extralarge&path=%2Fmnt%2FUSB%2FSamsung_T52%2F%24RECYCLE.BIN%2F%24R00B9TQ.zip&metadata=false Jan 12 19:50:22 rivo volumio[3285]: info: URI /mnt/USB/Samsung_T52/$RECYCLE.BIN/$R00B9TQ.zip Jan 12 19:50:22 rivo volumio[3285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 19:50:22 rivo volumio[3285]: Error: Unable to resolve or reject the same promise twice Jan 12 19:50:22 rivo volumio[3285]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 12 19:50:22 rivo volumio[3285]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Jan 12 19:50:22 rivo volumio[3285]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 12 19:50:22 rivo volumio[3285]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 12 19:50:22 rivo volumio[3285]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 12 19:50:22 rivo volumio[3285]: at Socket.emit (events.js:400:28) Jan 12 19:50:22 rivo volumio[3285]: at addChunk (internal/streams/readable.js:293:12) Jan 12 19:50:22 rivo volumio[3285]: at readableAddChunk (internal/streams/readable.js:263:11) Jan 12 19:50:22 rivo volumio[3285]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jan 12 19:50:22 rivo volumio[3285]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jan 12 19:50:22 rivo volumio[3285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 19:50:23 rivo sudo[23778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 19:49 Jan 12 19:50:23 rivo sudo[23778]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 07:07:40 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="90457dc663650a5d2f936402ef6c7dc1"