-- Logs begin at Mon 2026-04-27 09:18:25 CDT, end at Mon 2026-05-25 10:18:43 CDT. -- May 25 10:17:08 rivoplus qobuz-connect[11536]: 20260525 10:17:08.739 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:08 rivoplus qobuz-connect[11536]: 20260525 10:17:08.739 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:08 rivoplus qobuz-connect[11536]: 20260525 10:17:08.739 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:08 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:08 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:08 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:08 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:08 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:08 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:17:16 rivoplus qobuz-connect[11536]: 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. May 25 10:17:16 rivoplus qobuz-connect[11536]: 20260525 10:17:16.826 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:18 rivoplus qobuz-connect[11536]: 20260525 10:17:18.739 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:18 rivoplus qobuz-connect[11536]: 20260525 10:17:18.740 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:18 rivoplus qobuz-connect[11536]: 20260525 10:17:18.740 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:18 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:18 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:18 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:18 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:18 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:17:24 rivoplus qobuz-connect[11536]: 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. May 25 10:17:24 rivoplus qobuz-connect[11536]: 20260525 10:17:24.858 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:28 rivoplus qobuz-connect[11536]: 20260525 10:17:28.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:28 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:28 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:28 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:28 rivoplus qobuz-connect[11536]: 20260525 10:17:28.746 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:28 rivoplus qobuz-connect[11536]: 20260525 10:17:28.746 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:28 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:28 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:28 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:17:33 rivoplus qobuz-connect[11536]: 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. May 25 10:17:33 rivoplus qobuz-connect[11536]: 20260525 10:17:33.254 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:38 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:38 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:38 rivoplus qobuz-connect[11536]: 20260525 10:17:38.744 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:38 rivoplus qobuz-connect[11536]: 20260525 10:17:38.744 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:38 rivoplus qobuz-connect[11536]: 20260525 10:17:38.744 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:38 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:38 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:38 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:17:41 rivoplus qobuz-connect[11536]: 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. May 25 10:17:41 rivoplus qobuz-connect[11536]: 20260525 10:17:41.652 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:48 rivoplus qobuz-connect[11536]: 20260525 10:17:48.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:48 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:48 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:48 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:48 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:48 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:48 rivoplus qobuz-connect[11536]: 20260525 10:17:48.747 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:48 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:48 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:48 rivoplus qobuz-connect[11536]: 20260525 10:17:48.750 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:48 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:48 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:17:49 rivoplus qobuz-connect[11536]: 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. May 25 10:17:49 rivoplus qobuz-connect[11536]: 20260525 10:17:49.898 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:58 rivoplus qobuz-connect[11536]: 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. May 25 10:17:58 rivoplus qobuz-connect[11536]: 20260525 10:17:58.217 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:17:58 rivoplus qobuz-connect[11536]: 20260525 10:17:58.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:17:58 rivoplus qobuz-connect[11536]: 20260525 10:17:58.740 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:17:58 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:58 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:17:58 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:17:58 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:17:58 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:17:58 rivoplus qobuz-connect[11536]: 20260525 10:17:58.744 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:17:58 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:17:58 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:17:58 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:17:58 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:18:05 rivoplus qobuz-connect[11536]: 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. May 25 10:18:05 rivoplus qobuz-connect[11536]: 20260525 10:18:05.809 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:18:08 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:08.402-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.104:37984 May 25 10:18:08 rivoplus qobuz-connect[11536]: 20260525 10:18:08.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:18:08 rivoplus qobuz-connect[11536]: 20260525 10:18:08.741 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:18:08 rivoplus qobuz-connect[11536]: 20260525 10:18:08.741 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:18:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:08 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:18:08 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:18:08 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:18:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:18:08 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:08 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:18:08 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:18:08 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.522-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.104:37984 @ 0x1c00300" latency=398.152616ms platform=PLATFORM_ANDROID version=5.260413.0 May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.525-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.104:37984 @ 0x1c00300" latency=396.195031ms timeout=10s May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.525-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" May 25 10:18:12 rivoplus volumio[3097]: info: Received Get System Info May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 10:18:12 rivoplus volumio[3097]: info: Discovery: Getting this device information May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.531-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" name=Rivoplus May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.532-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" language=es May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.535-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" timezone=America/Havana May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.538-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" available=true connected=true macAddress=02:00:00:1a:23:01 ip4Address=192.168.1.102/24 ip6Address= May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.540-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 10:18:12 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:12.540-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" setupComplete=true May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 25 10:18:12 rivoplus volumio[3097]: No protocol specified May 25 10:18:12 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 10:18:12 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 10:18:12 rivoplus volumio[3097]: amixer -c 1 info | grep "Comtrue-inc UAC2 Device" May 25 10:18:12 rivoplus volumio[3097]: No protocol specified May 25 10:18:12 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:12 rivoplus volumio[3097]: Card hw:1 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' May 25 10:18:12 rivoplus volumio[3097]: No protocol specified May 25 10:18:12 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:12 rivoplus volumio[3097]: amixer -c 5 info | grep "DENAFRIPS USB Audio V3.12 I1.6" May 25 10:18:12 rivoplus volumio[3097]: No protocol specified May 25 10:18:12 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:12 rivoplus volumio[3097]: Card hw:5 'I16'/'DENAFRIPS DENAFRIPS USB Audio V3.12 I1.6 at usb-xhci-hcd.0.auto-1.4.2, high spe' May 25 10:18:12 rivoplus volumio[3097]: No protocol specified May 25 10:18:12 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:12 rivoplus volumio[3097]: amixer -c 5 info | grep "DENAFRIPS USB Audio V3.12 I1.6" May 25 10:18:13 rivoplus volumio[3097]: No protocol specified May 25 10:18:13 rivoplus volumio[3097]: xcb_connection_has_error() returned true May 25 10:18:13 rivoplus volumio[3097]: Card hw:5 'I16'/'DENAFRIPS DENAFRIPS USB Audio V3.12 I1.6 at usb-xhci-hcd.0.auto-1.4.2, high spe' May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.059-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" selectedOutputId=5 May 25 10:18:13 rivoplus volumio[3097]: info: Received Get System Info May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 10:18:13 rivoplus volumio[3097]: info: Discovery: Getting this device information May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.338-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" currentVersion=3.912 latestVersion=3.912 May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.339-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.104:37984 @ 0x1c00300" status=UPDATE_STATUS_NONE progress=0 May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.339-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" userId= May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.339-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" providers=3 May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.343-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" plugins=0 May 25 10:18:13 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:13 rivoplus qobuz-connect[11536]: 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. May 25 10:18:13 rivoplus qobuz-connect[11536]: 20260525 10:18:13.345 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.348-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" state=STATUS_PLAYING positionMs=80039 volume=8 May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.349-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" id= title=Thriller May 25 10:18:13 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:13.645-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.104:37984 @ 0x1c00300" latency=396.191844ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 25 10:18:16 rivoplus sudo[20134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 10:18:16 rivoplus sudo[20134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 10:18:16 rivoplus sudo[20136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 10:18:16 rivoplus sudo[20136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 10:18:16 rivoplus sudo[20134]: pam_unix(sudo:session): session closed for user root May 25 10:18:16 rivoplus sudo[20136]: pam_unix(sudo:session): session closed for user root May 25 10:18:16 rivoplus volumio[3097]: verbose: New Socket.io Connection to 192.168.1.102 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 13; SM-A515F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 25 10:18:17 rivoplus sudo[20144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 10:18:17 rivoplus sudo[20144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 10:18:17 rivoplus sudo[20144]: pam_unix(sudo:session): session closed for user root May 25 10:18:17 rivoplus sudo[20146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 10:18:17 rivoplus sudo[20146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 10:18:17 rivoplus sudo[20146]: pam_unix(sudo:session): session closed for user root May 25 10:18:17 rivoplus volumio[3097]: verbose: New Socket.io Connection to 192.168.1.102 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 13; SM-A515F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetVisibleSources May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetQueue May 25 10:18:18 rivoplus volumio[3097]: info: CoreStateMachine::getQueue May 25 10:18:18 rivoplus volumio[3097]: info: CorePlayQueue::getQueue May 25 10:18:18 rivoplus volumio[3097]: info: Listing playlists May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 10:18:18 rivoplus volumio[3097]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 25 10:18:18 rivoplus volumio[3097]: info: Received Get System Info May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 10:18:18 rivoplus volumio[3097]: info: Discovery: Getting this device information May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 10:18:18 rivoplus qobuz-connect[11536]: 20260525 10:18:18.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:18:18 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:18:18 rivoplus qobuz-connect[11536]: 20260525 10:18:18.743 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:18:18 rivoplus qobuz-connect[11536]: 20260525 10:18:18.743 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:18:18 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:18 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:18:18 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:18:18 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:18.766-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" state=STATUS_PLAYING positionMs=85920 volume=8 May 25 10:18:18 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:18.766-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" id= title=Thriller May 25 10:18:18 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:18:19 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 10:18:19 rivoplus volumio[3097]: info: Received Get System Info May 25 10:18:19 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 10:18:19 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 10:18:19 rivoplus volumio[3097]: info: Discovery: Getting this device information May 25 10:18:19 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:19 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 10:18:20 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 10:18:21 rivoplus qobuz-connect[11536]: 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. May 25 10:18:21 rivoplus qobuz-connect[11536]: 20260525 10:18:21.155 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:18:23 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 10:18:23 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 10:18:25 rivoplus volumio[3097]: info: Preload queue cleared May 25 10:18:27 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 10:18:28 rivoplus qobuz-connect[11536]: 20260525 10:18:28.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:18:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:28 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:18:28 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:18:28 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:18:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:18:28 rivoplus qobuz-connect[11536]: 20260525 10:18:28.746 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:18:28 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:28 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:18:28 rivoplus qobuz-connect[11536]: 20260525 10:18:28.747 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:18:28 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:18:28 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:28.751-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" state=STATUS_PLAYING positionMs=92450 volume=8 May 25 10:18:28 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:28.751-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" id= title=Thriller May 25 10:18:28 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:18:29 rivoplus qobuz-connect[11536]: 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. May 25 10:18:29 rivoplus qobuz-connect[11536]: 20260525 10:18:29.062 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:18:37 rivoplus qobuz-connect[11536]: 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. May 25 10:18:37 rivoplus qobuz-connect[11536]: 20260525 10:18:37.133 [11536.11536] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 25 10:18:37 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:37 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:37 rivoplus volumio[3097]: info: Retrieving Cloud Streaming UI May 25 10:18:37 rivoplus volumio[3097]: info: Getting Tidal Cloud Configuration May 25 10:18:37 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:38 rivoplus volumio[3097]: info: Getting Qobuz Cloud Configuration May 25 10:18:38 rivoplus volumio[3097]: info: Asking plugin for UI Config May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:38 rivoplus volumio[3097]: info: Getting Spotify Cloud Configuration May 25 10:18:38 rivoplus volumio[3097]: info: Asking plugin for UI Config May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:38 rivoplus volumio[3097]: info: Saving Spotify Acccount May 25 10:18:38 rivoplus volumio[3097]: info: Got it May 25 10:18:38 rivoplus volumio[3097]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 25 10:18:38 rivoplus volumio[3097]: info: Got Tidal Cloud Configuration May 25 10:18:38 rivoplus volumio[3097]: info: Got it May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources May 25 10:18:38 rivoplus qobuz-connect[11536]: 20260525 10:18:38.740 [11536.11536] INFO ProtocolHandler: [0xab0b97d0]: Need to send state update May 25 10:18:38 rivoplus qobuz-connect[11536]: 20260525 10:18:38.741 [11536.11536] INFO QConnectMessageSender: [0xab1a87e0]: Sending StateUpdated message May 25 10:18:38 rivoplus qobuz-connect[11536]: 20260525 10:18:38.741 [11536.11536] INFO QwspMessageSender: [0xab1a87d0]: Sending Payload message May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::servicePushState May 25 10:18:38 rivoplus volumio[3097]: info: CoreStateMachine::pushState May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioPushState May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::volumioGetState May 25 10:18:38 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output update for this device May 25 10:18:38 rivoplus volumio[3097]: info: MRS: Pushing multiroomSync output May 25 10:18:38 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:38.770-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" state=STATUS_PLAYING positionMs=98116 volume=8 May 25 10:18:38 rivoplus volumio5-onboarding[3737]: time=2026-05-25T10:18:38.770-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.104:37984 @ 0x1c00300" id= title=Thriller May 25 10:18:38 rivoplus volumio[3097]: info: Signalling Playback active due to playback status change May 25 10:18:38 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 25 10:18:39 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 25 10:18:41 rivoplus volumio[3097]: info: Disabling MyMusic plugin upnp May 25 10:18:41 rivoplus sudo[20274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 25 10:18:41 rivoplus sudo[20274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 10:18:41 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... May 25 10:18:41 rivoplus volumio[3097]: error: Upnp client error: Error: This socket has been ended by the other party May 25 10:18:41 rivoplus volumio[3097]: ------------------------------------ BT MESSAGE: BT STATUS: running May 25 10:18:42 rivoplus volumio[3097]: info: Enabling MyMusic plugin upnp May 25 10:18:42 rivoplus volumio[3097]: info: Enabling plugin upnp May 25 10:18:42 rivoplus volumio[3097]: info: Loading plugin "upnp"... May 25 10:18:42 rivoplus volumio[3097]: info: [1779718722811] Starting Upmpd Daemon May 25 10:18:42 rivoplus volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 10:18:42 rivoplus volumio[3097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 10:18:42 rivoplus volumio[3097]: Error: listen EADDRINUSE: address already in use :::6599 May 25 10:18:42 rivoplus volumio[3097]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 25 10:18:42 rivoplus volumio[3097]: at listenInCluster (net.js:1379:12) May 25 10:18:42 rivoplus volumio[3097]: at Server.listen (net.js:1465:7) May 25 10:18:42 rivoplus volumio[3097]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 25 10:18:42 rivoplus volumio[3097]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 25 10:18:42 rivoplus volumio[3097]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 25 10:18:42 rivoplus volumio[3097]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 25 10:18:42 rivoplus volumio[3097]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 25 10:18:42 rivoplus volumio[3097]: code: 'EADDRINUSE', May 25 10:18:42 rivoplus volumio[3097]: errno: -98, May 25 10:18:42 rivoplus volumio[3097]: syscall: 'listen', May 25 10:18:42 rivoplus volumio[3097]: address: '::', May 25 10:18:42 rivoplus volumio[3097]: port: 6599 May 25 10:18:42 rivoplus volumio[3097]: } May 25 10:18:42 rivoplus volumio[3097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 10:18:43 rivoplus sudo[20289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-25 10:17 May 25 10:18:43 rivoplus sudo[20289]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"