Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.253+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.266+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.966381ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.280+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=25.668207ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.289+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=35.544346ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.291+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=http://pushupdates.volumio.org duration=36.195587ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.372+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://www.googleapis.com duration=117.325051ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.391+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=135.423157ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.402+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://database.volumio.cloud duration=145.308045ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.412+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://securetoken.googleapis.com duration=157.074073ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.414+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://functions.volumio.cloud duration=157.55739ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.416+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://functions.volumio.cloud duration=160.26878ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.596+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=https://google.com duration=341.44023ms Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.864+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=http://cddb.volumio.org duration=609.090087ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:16:10 rivo volumio[3446]: info: Discovery: Getting this device information Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:10 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:16:10 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.214 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:16:10 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:16:10 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:10.947+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=873.245985ms timeout=10s endpoint=http://plugins.volumio.org duration=692.836935ms Oct 12 11:16:32 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 11:16:32 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:16:32 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:32 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:32 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:16:32 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:16:32 rivo volumio[3446]: info: Listing playlists Oct 12 11:16:32 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:32 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:32 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:16:32 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:16:32 rivo volumio[3446]: info: Listing playlists Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 12 11:16:36 rivo volumio[3446]: info: Playing Remote Device: http://192.168.1.103 Oct 12 11:16:36 rivo volumio[3446]: info: Oct 12 11:16:36 rivo volumio[3446]: ---------------------------- Client requests Volumio play Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:16:36 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:16:36 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:16:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:36 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:16:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:16:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:16:36 rivo volumio[3446]: info: [1791796596172] ControllerWebradio::resume Oct 12 11:16:36 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:16:36 rivo volumio[3446]: info: sendMpdCommand play took 4 milliseconds Oct 12 11:16:36 rivo volumio[3446]: info: Done playing: Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 12 11:16:37 rivo volumio[3446]: info: Playing Remote Device: http://192.168.1.103 Oct 12 11:16:37 rivo volumio[3446]: info: Oct 12 11:16:37 rivo volumio[3446]: ---------------------------- Client requests Volumio play Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:16:37 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:16:37 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:16:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:37 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:16:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:16:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:16:37 rivo volumio[3446]: info: [1791796597233] ControllerWebradio::resume Oct 12 11:16:37 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:16:37 rivo volumio[3446]: info: sendMpdCommand play took 4 milliseconds Oct 12 11:16:37 rivo volumio[3446]: info: Done playing: Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 12 11:16:42 rivo volumio[3446]: info: Setting Remote Device Volume: http://192.168.1.103 Oct 12 11:16:42 rivo volumio[3446]: info: Oct 12 11:16:42 rivo volumio[3446]: ---------------------------- Client requests Volume 50 Oct 12 11:16:42 rivo volumio[3446]: info: VolumeController::SetAlsaVolume50 Oct 12 11:16:42 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.417+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130247 volume=50 Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.417+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:42 rivo volumio[3446]: info: Oct 12 11:16:42 rivo volumio[3446]: ---------------------------- Client requests Volume 46 Oct 12 11:16:42 rivo volumio[3446]: info: VolumeController::SetAlsaVolume46 Oct 12 11:16:42 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.454+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130438 volume=46 Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.455+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:42 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:42 rivo volumio[3446]: info: Oct 12 11:16:42 rivo volumio[3446]: ---------------------------- Client requests Volume 45 Oct 12 11:16:42 rivo volumio[3446]: info: VolumeController::SetAlsaVolume45 Oct 12 11:16:42 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.567+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130509 volume=45 Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.568+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:42 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:42 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:42 rivo volumio[3446]: info: Oct 12 11:16:42 rivo volumio[3446]: ---------------------------- Client requests Volume 43 Oct 12 11:16:42 rivo volumio[3446]: info: VolumeController::SetAlsaVolume43 Oct 12 11:16:42 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.710+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130509 volume=43 Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.712+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:42 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:42 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:42 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:42 rivo volumio[3446]: info: Oct 12 11:16:42 rivo volumio[3446]: ---------------------------- Client requests Volume 42 Oct 12 11:16:42 rivo volumio[3446]: info: VolumeController::SetAlsaVolume42 Oct 12 11:16:42 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:42 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:42 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.886+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130812 volume=42 Oct 12 11:16:42 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:42.887+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:42 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:42 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 12 11:16:42 rivo volumio[3446]: info: Playing Remote Device: http://192.168.1.103 Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 40 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume40 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.032+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=130812 volume=40 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.034+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 39 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume39 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.106+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131085 volume=39 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.107+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 38 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume38 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.157+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131085 volume=38 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.157+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 36 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume36 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.236+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131085 volume=36 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.237+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 35 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume35 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.287+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131085 volume=35 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.288+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 34 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume34 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.358+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131337 volume=34 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.364+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 33 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume33 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.400+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131337 volume=33 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.401+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 32 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume32 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.468+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131337 volume=32 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.468+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 31 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume31 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.510+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131337 volume=31 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.515+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volume 30 Oct 12 11:16:43 rivo volumio[3446]: info: VolumeController::SetAlsaVolume30 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:16:43 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.576+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=131337 volume=30 Oct 12 11:16:43 rivo volumio5-onboarding[3918]: time=2026-10-12T11:16:43.577+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: Oct 12 11:16:43 rivo volumio[3446]: ---------------------------- Client requests Volumio play Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:16:43 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:16:43 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:16:43 rivo volumio[3446]: info: [1791796603598] ControllerWebradio::resume Oct 12 11:16:43 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:16:43 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:16:43 rivo volumio[3446]: info: Done setting volume on: Oct 12 11:16:43 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:16:43 rivo volumio[3446]: info: sendMpdCommand play took 26 milliseconds Oct 12 11:16:43 rivo volumio[3446]: info: Done playing: Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:51 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:16:51 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:16:51 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:16:51 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:51 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:16:51 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:16:51 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:16:51 rivo volumio[3446]: info: [1791796611213] ControllerWebradio::resume Oct 12 11:16:51 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:16:51 rivo volumio[3446]: info: sendMpdCommand play took 1 milliseconds Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:16:54 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:16:54 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:16:54 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:16:54 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:54 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:16:54 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:16:54 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:16:54 rivo volumio[3446]: info: [1791796614424] ControllerWebradio::resume Oct 12 11:16:54 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:16:54 rivo volumio[3446]: info: sendMpdCommand play took 1 milliseconds Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.095+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.214:57594 @ 0x25b8cf0" latency=429.664581ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.214+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.227+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.916726ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.245+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=28.515876ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.252+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=36.004169ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.255+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=http://pushupdates.volumio.org duration=37.373383ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.329+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://www.googleapis.com duration=114.29572ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.352+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=136.52326ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.376+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://functions.volumio.cloud duration=158.964015ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.378+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://functions.volumio.cloud duration=162.655181ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.390+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://securetoken.googleapis.com duration=174.643209ms Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.391+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://database.volumio.cloud duration=172.950652ms Oct 12 11:17:03 rivo sudo[10720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:17:03 rivo sudo[10720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:03 rivo sudo[10721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:17:03 rivo sudo[10721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.550+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=https://google.com duration=335.995761ms Oct 12 11:17:03 rivo sudo[10720]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:03 rivo sudo[10721]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:03 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:03 rivo sudo[10727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:17:03 rivo sudo[10727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:03 rivo sudo[10729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:17:03 rivo sudo[10729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:03 rivo sudo[10727]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:03 rivo sudo[10729]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.844+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=http://cddb.volumio.org duration=627.872122ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 11:17:03 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:03 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:03 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:03 rivo volumio[3446]: info: Listing playlists Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:17:03 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:17:03 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:03.992+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-120.968977ms timeout=10s endpoint=http://plugins.volumio.org duration=774.800398ms Oct 12 11:17:06 rivo kernel: WLC_E_IF: NO_IF set, event Ignored Oct 12 11:17:06 rivo kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Oct 12 11:17:06 rivo kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : fe:4b:68:0e:d1:dd on primary ndev Oct 12 11:17:14 rivo volumio[3446]: info: VolumeController::SetAlsaVolume40 Oct 12 11:17:14 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.458+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=162197 volume=40 Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.459+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:14 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:14 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:14 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:14 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:14 rivo volumio[3446]: info: VolumeController::SetAlsaVolume65 Oct 12 11:17:14 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.600+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=162307 volume=65 Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.600+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:14 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:14 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:14 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:14 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:14 rivo volumio[3446]: info: VolumeController::SetAlsaVolume70 Oct 12 11:17:14 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:14 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:14 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:14 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.993+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=162788 volume=70 Oct 12 11:17:14 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:14.995+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:15 rivo volumio[3446]: info: VolumeController::SetAlsaVolume65 Oct 12 11:17:15 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.183+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=162947 volume=65 Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.184+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:15 rivo volumio[3446]: info: VolumeController::SetAlsaVolume55 Oct 12 11:17:15 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.291+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=163079 volume=55 Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.291+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:15 rivo volumio[3446]: info: VolumeController::SetAlsaVolume50 Oct 12 11:17:15 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:15 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:15 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:15 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.411+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_PAUSED positionMs=163197 volume=50 Oct 12 11:17:15 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:15.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:15 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:15 rivo volumio[3446]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:17:22 rivo volumio[3446]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 11:17:22 rivo volumio[3446]: info: Received Get System Version Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 11:17:22 rivo volumio[3446]: info: Received Get System Info Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:17:22 rivo volumio[3446]: info: Discovery: Getting this device information Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:22 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:22 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.701+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-117.610127ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.720+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.733+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=13.367148ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.746+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=26.139197ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.755+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=http://pushupdates.volumio.org duration=34.782441ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.759+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=38.017911ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.836+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://www.googleapis.com duration=116.289803ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.859+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=138.527997ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.868+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://database.volumio.cloud duration=147.123314ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.878+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://functions.volumio.cloud duration=157.337928ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.878+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://functions.volumio.cloud duration=156.936207ms Oct 12 11:17:25 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:25.879+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://securetoken.googleapis.com duration=158.53125ms Oct 12 11:17:25 rivo sudo[10777]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:17:25 rivo sudo[10777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:25 rivo sudo[10779]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:17:25 rivo sudo[10777]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:25 rivo sudo[10779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:25 rivo sudo[10779]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:26 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:26 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:26.058+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=https://google.com duration=337.509405ms Oct 12 11:17:26 rivo sudo[10785]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:17:26 rivo sudo[10785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:26 rivo sudo[10787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:17:26 rivo sudo[10787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:26 rivo sudo[10785]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:26 rivo sudo[10787]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:26 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:26 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:26 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:26 rivo volumio[3446]: info: Listing playlists Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:17:26 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:17:26 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:26.344+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=http://cddb.volumio.org duration=623.014317ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 11:17:26 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:26.452+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-119.809928ms timeout=10s endpoint=http://plugins.volumio.org duration=730.925786ms Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:27 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:17:27 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:17:27 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:17:27 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:27 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:17:27 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:17:27 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:17:27 rivo volumio[3446]: info: [1791796647663] ControllerWebradio::resume Oct 12 11:17:27 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:17:27 rivo volumio[3446]: info: sendMpdCommand play took 1 milliseconds Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:28 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:17:28 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:17:28 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:17:28 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:28 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:17:28 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:17:28 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:17:28 rivo volumio[3446]: info: [1791796648657] ControllerWebradio::resume Oct 12 11:17:28 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:17:28 rivo volumio[3446]: info: sendMpdCommand play took 1 milliseconds Oct 12 11:17:36 rivo kernel: usb 1-2: new high-speed USB device number 6 using xhci-hcd Oct 12 11:17:36 rivo kernel: usb 1-2: New USB device found, idVendor=2972, idProduct=0120 Oct 12 11:17:36 rivo kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 12 11:17:36 rivo kernel: usb 1-2: Product: FIIO K13 R2R Oct 12 11:17:36 rivo kernel: usb 1-2: Manufacturer: GuangZhou FiiO Electronics Co.,Ltd Oct 12 11:17:36 rivo kernel: usb 1-2: Unsupported device Oct 12 11:17:36 rivo kernel: hid-generic 0003:2972:0120.0003: hiddev0,hidraw0: USB HID v1.10 Device [GuangZhou FiiO Electronics Co.,Ltd FIIO K13 R2R] on usb-xhci-hcd.0.auto-2/input3 Oct 12 11:17:36 rivo (udev-worker)[10812]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Oct 12 11:17:36 rivo volumio[3446]: info: Oct 12 11:17:36 rivo volumio[3446]: ---------------------------- USB Audio Device Attached Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::Close All Modals sent Oct 12 11:17:36 rivo volumio[3446]: info: Preparing to save Alsa Options, stopping services first Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioStop Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::stop Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::updateTrackBlock Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrackBlock Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::stPlaybackTimer Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:36 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:36 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:36 rivo volumio[3446]: info: CoreStateMachine::serviceStop Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::serviceStop Oct 12 11:17:36 rivo volumio[3446]: info: [1791796656646] ControllerWebradio::stop Oct 12 11:17:36 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 11:17:36 rivo volumio[3446]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"FIIO K13 R2R","alsacard":"R2R"},"i2s":false} Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:17:36 rivo volumio[3446]: info: Setting mixer GuangZhou FiiO Electronics Co. for card FIIO K13 R2R Oct 12 11:17:36 rivo volumio[3446]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:36 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:36 rivo volumio[3446]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 11:17:36 rivo vtcs[10318]: [2026-10-12 11:17:36.762] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 12 11:17:36 rivo vtcs[10318]: [2026-10-12 11:17:36.762] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 12 11:17:36 rivo vtcs[10318]: [2026-10-12 11:17:36.762] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 11:17:36 rivo volumio[3446]: info: Updating Volume Controller Parameters: Device: 5 Name: FIIO K13 R2R Mixer: GuangZhou FiiO Electronics Co. Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 11:17:36 rivo volumio[3446]: info: Disabling external Volume Control Oct 12 11:17:36 rivo sudo[10831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:17:36 rivo sudo[10831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:36 rivo volumio[3446]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 11:17:36 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:36.843+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=0 volume=50 Oct 12 11:17:36 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:36.847+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:36 rivo volumio[3446]: info: Preparing to generate the ALSA configuration file Oct 12 11:17:36 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 11:17:36 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 11:17:36 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 11:17:36 rivo sudo[10831]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:36 rivo sudo[10838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:17:36 rivo volumio[3446]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 11:17:36 rivo volumio[3446]: info: Reading ALSA contributions from plugins. Oct 12 11:17:36 rivo sudo[10838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:36 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:36 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:36 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:36 rivo volumio[3446]: info: sendMpdCommand stop took 346 milliseconds Oct 12 11:17:36 rivo volumio[3446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'GuangZhou FiiO Electronics Co.',0 Oct 12 11:17:37 rivo sudo[10843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:17:37 rivo sudo[10843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo sudo[10838]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo sudo[10843]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 11:17:37 rivo sudo[10850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:17:37 rivo sudo[10850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 11:17:37 rivo qobuz-connect[10229]: 20261012 11:17:37.170 [10229.10229] INFO ContentFetcher: [0xabe245b8]: Canceling download Oct 12 11:17:37 rivo qobuz-connect[10229]: 20261012 11:17:37.171 [10229.10229] INFO CloudClient: [0xabca52b8]: Disconnecting from the cloud Oct 12 11:17:37 rivo qobuz-connect[10229]: 20261012 11:17:37.171 [10229.10229] INFO CloudClient: [0xabca52b8]: Connection closed Oct 12 11:17:37 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:17:37 rivo volumio[3446]: info: VolumeController:: Volume=undefined Mute =false Oct 12 11:17:37 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:37 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:37 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:37 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:37 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:37 rivo qobuz-connect[10229]: 20261012 11:17:37.205 [10229.10229] INFO SampleApp: Stopping Local configuration server Oct 12 11:17:37 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:37.187+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=3360 volume=50 Oct 12 11:17:37 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:37.188+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:37 rivo volumio[3446]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 11:17:37 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: Asound.conf file unchanged, so no further update is needed Oct 12 11:17:37 rivo volumio[3446]: info: Output device has changed, restarting MPD Oct 12 11:17:37 rivo volumio[3446]: info: Output device has changed, restarting Shairport Sync Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:17:37 rivo sudo[10855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 11:17:37 rivo sudo[10855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo sudo[10855]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo sudo[10857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 11:17:37 rivo sudo[10857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo volumio[3446]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 11:17:37 rivo volumio[3446]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:37 rivo volumio[3446]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 11:17:37 rivo sudo[10866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:17:37 rivo sudo[10866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo sudo[10868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 11:17:37 rivo sudo[10868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 11:17:37 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 11:17:37 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 11:17:37 rivo sudo[10875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 11:17:37 rivo sudo[10875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 11:17:37 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: Not Reporting Auto name since its the default one Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 12 11:17:37 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: Not Reporting Auto name since its the default one Oct 12 11:17:37 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 11:17:37 rivo volumio[3446]: info: MPD Permissions set Oct 12 11:17:37 rivo volumio[3446]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 11:17:37 rivo volumio[3446]: info: VolumeController:: Starting alsactl monitor Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:37 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:37 rivo sudo[10866]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:17:37 rivo sudo[10875]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo sudo[10868]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo sudo[10889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 11:17:37 rivo sudo[10889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo qobuz-connect[10229]: 20261012 11:17:37.642 [10229.10229] INFO SampleApp: shat down connection on UNIX socket Oct 12 11:17:37 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:17:37 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 11:17:37 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:17:37 rivo sudo[10850]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:17:37 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 11:17:37 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 11:17:37 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:17:37 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 11:17:37 rivo sudo[10889]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:17:37 rivo sudo[10903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:17:37 rivo sudo[10903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo sudo[10903]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:37 rivo sudo[10908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:17:37 rivo sudo[10908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo sudo[10909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:17:37 rivo sudo[10909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 11:17:37 rivo volumio[3446]: info: Not Reporting Auto name since its the default one Oct 12 11:17:37 rivo volumio[3446]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 11:17:37 rivo volumio[3446]: info: QobuzConnect: setDeactiveState invoked Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:37 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:37 rivo volumio[3446]: info: Executing endpoint qc_getconfig Oct 12 11:17:37 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 11:17:37 rivo sudo[10908]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:17:38 rivo sudo[10909]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.046 [10901.10901] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 11:17:38 rivo sudo[10886]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 11:17:38 rivo sudo[10886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 11:17:38 rivo volumio[3446]: info: Starting Shairport Sync Oct 12 11:17:38 rivo sudo[10918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:17:38 rivo sudo[10918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.061 [10901.10901] INFO VolumeManager: [0xac68a148]: Setting new playback volume: 75 Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.061 [10901.10901] INFO VolumeManager: [0xac68a148]: Setting new mute state: 0 Oct 12 11:17:38 rivo sudo[10886]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.061 [10901.10901] INFO AudioStreamManager: [0xac689ea0]: Setting new audio download buffer size: 1048576 Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.090 [10901.10901] INFO QobuzConnect: [0xac68aa10]: Client initialized! Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.091 [10901.10901] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 11:17:38 rivo volumio[3446]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 11:17:38 rivo volumio[3446]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 11:17:38 rivo volumio[3446]: info: Raat Daemon started successfully Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 11:17:38 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.182 [10901.10901] INFO LocalConfigManager: [0xac689bc8]: Starting Local Configuration server Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.182 [10901.10901] INFO SampleApp: Starting Local configuration server Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.183 [10901.10901] INFO SampleApp: Connected to UNIX socket client 0xac674908 Oct 12 11:17:38 rivo sudo[10935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 11:17:38 rivo sudo[10935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:17:38 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:17:38 rivo qobuz-connect[10901]: 20261012 11:17:38.194 [10901.10901] INFO SampleApp: Playback volume changed: 75 Oct 12 11:17:38 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:17:38 rivo sudo[10918]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 11:17:38 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 11:17:38 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 11:17:38 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:38 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:38 rivo volumio[3446]: info: Executing endpoint restartRAATSocket Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:17:38 rivo volumio[3446]: info: Raat Daemon started successfully Oct 12 11:17:38 rivo sudo[10957]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 11:17:38 rivo sudo[10957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo sudo[10955]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 11:17:38 rivo sudo[10955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 11:17:38 rivo sudo[10935]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo sudo[10949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 11:17:38 rivo sudo[10949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo volumio[3446]: info: Executing endpoint restartRAATSocket Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:17:38 rivo volumio[3446]: info: Shairport-Sync Started Oct 12 11:17:38 rivo sudo[10955]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:38 rivo sudo[10957]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo volumio[3446]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 12 11:17:38 rivo sudo[10949]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 11:17:38 rivo volumio[3446]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 12 11:17:38 rivo sudo[10972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 11:17:38 rivo sudo[10972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:38 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:38 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:38 rivo volumio[3446]: info: Listing playlists Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 11:17:38 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 11:17:38 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 11:17:38 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 11:17:38 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 11:17:38 rivo sudo[10972]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:38 rivo volumio[3446]: info: Raat Daemon started successfully Oct 12 11:17:38 rivo volumio[3446]: info: Executing endpoint restartRAATSocket Oct 12 11:17:38 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 11:17:38 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:38 rivo volumio[3446]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 12 11:17:39 rivo volumio[3446]: info: TidalConnect service stoped! Oct 12 11:17:40 rivo volumio[3446]: info: TidalConnect service stoped! Oct 12 11:17:40 rivo volumio[3446]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:17:40 rivo volumio[3446]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:17:40 rivo sudo[11021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:17:40 rivo sudo[11021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:40 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 11:17:40 rivo sudo[11021]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:40 rivo volumio[3446]: info: TidalConnect service stoped! Oct 12 11:17:40 rivo volumio[3446]: info: TidalConnect service stoped! Oct 12 11:17:40 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 11:17:40 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 11:17:40 rivo volumio[3446]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:17:40 rivo volumio[3446]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 11:17:40 rivo volumio[3446]: info: Executing endpoint tc_getconfig Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 12 11:17:40 rivo volumio[3446]: info: RAAT: Requesting Headphone Status Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:17:40 rivo vtcs[11025]: STARTING TidalConnect services, version: 1.6.1 Oct 12 11:17:40 rivo vtcs[11025]: STARTED TidalConnect services. Oct 12 11:17:40 rivo volumio[3446]: info: Executing endpoint tc_connect Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 12 11:17:40 rivo volumio[3446]: info: Connecting to TidalConnect Oct 12 11:17:40 rivo sudo[11039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 11:17:40 rivo sudo[11039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::servicePushState Oct 12 11:17:40 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::servicePushState Oct 12 11:17:40 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.835+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=3360 volume=50 Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.835+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.837+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=3360 volume=50 Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 11:17:40 rivo volumio[3446]: info: CoreStateMachine::getcurrentVolume Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 11:17:40 rivo volumio[3446]: info: RAAT: Requesting Headphone Status Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:17:40 rivo sudo[11039]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:40 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:40 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:40 rivo volumio[3446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'GuangZhou FiiO Electronics Co.',0 Oct 12 11:17:40 rivo volumio[3446]: info: VolumeController:: Volume=undefined Mute =false Oct 12 11:17:40 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:40 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:40 rivo volumio[3446]: info: CoreStateMachine::updateTrackBlock Oct 12 11:17:40 rivo volumio[3446]: info: CorePlayQueue::getTrackBlock Oct 12 11:17:40 rivo volumio[3446]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.991+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=3360 volume=50 Oct 12 11:17:40 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:40.992+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:41 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:41 rivo volumio[3446]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'GuangZhou FiiO Electronics Co.',0 Oct 12 11:17:41 rivo volumio[3446]: info: VolumeController:: Volume=undefined Mute =false Oct 12 11:17:41 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:41 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:41 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:41 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:41 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:41 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:41 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:41 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:41.075+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=3360 volume=50 Oct 12 11:17:41 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:41.076+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" title=Www.spotify.com Oct 12 11:17:41 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:41 rivo volumio[3446]: info: RAAT: Requesting Headphone Status Oct 12 11:17:41 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 11:17:42 rivo mpd[10936]: 2026-10-12T11:17:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 11:17:42 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 11:17:42 rivo sudo[10857]: pam_unix(sudo:session): session closed for user root Oct 12 11:17:42 rivo volumio[3446]: error: updateQueue error: null Oct 12 11:17:43 rivo volumio[3446]: info: TidalConnect service started! Oct 12 11:17:43 rivo volumio[3446]: info: TidalConnect service started! Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.444+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.460+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=16.1906ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.473+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=25.583651ms Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 11:17:44 rivo volumio[3446]: info: Discovery: Getting this device information Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:44 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.484+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=37.332653ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.486+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=http://pushupdates.volumio.org duration=36.261762ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.560+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://www.googleapis.com duration=114.651642ms Oct 12 11:17:44 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.214 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 11:17:44 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.585+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=139.002576ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.594+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://database.volumio.cloud duration=144.596331ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.603+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://functions.volumio.cloud duration=156.880071ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.605+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://functions.volumio.cloud duration=157.704285ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.606+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://securetoken.googleapis.com duration=159.67374ms Oct 12 11:17:44 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:44.782+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=https://google.com duration=336.96942ms Oct 12 11:17:45 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:45.182+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=http://plugins.volumio.org duration=732.583682ms Oct 12 11:17:45 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:45.346+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.214:57594 @ 0x25b8cf0" latency=-113.770269ms timeout=10s endpoint=http://cddb.volumio.org duration=897.64325ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPlay Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::play index undefined Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 11:17:46 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::startPlaybackTimer Oct 12 11:17:46 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 12 11:17:46 rivo volumio[3446]: info: [1791796666223] ControllerWebradio::clearAddPlayTrack Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand stop took 1 milliseconds Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 11:17:46 rivo volumio[3446]: info: Oct 12 11:17:46 rivo volumio[3446]: ---------------------------- MPD announces system playlist update Oct 12 11:17:46 rivo volumio[3446]: info: Ignoring MPD Status Update Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand clear took 1 milliseconds Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" Oct 12 11:17:46 rivo volumio[3446]: error: updateQueue error: null Oct 12 11:17:46 rivo volumio[3446]: info: ------------------------------ 4ms Oct 12 11:17:46 rivo volumio[3446]: info: Oct 12 11:17:46 rivo volumio[3446]: ---------------------------- MPD announces system playlist update Oct 12 11:17:46 rivo volumio[3446]: info: Ignoring MPD Status Update Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99235201" took 310 milliseconds Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand play Oct 12 11:17:46 rivo volumio[3446]: info: ------------------------------ 2ms Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand play took 3 milliseconds Oct 12 11:17:46 rivo volumio[3446]: info: Oct 12 11:17:46 rivo volumio[3446]: ---------------------------- MPD announces state update: player Oct 12 11:17:46 rivo volumio[3446]: info: ControllerMpd::getState Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:17:46 rivo volumio[3446]: info: Oct 12 11:17:46 rivo volumio[3446]: ---------------------------- MPD announces state update: player Oct 12 11:17:46 rivo volumio[3446]: info: ControllerMpd::getState Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand status Oct 12 11:17:46 rivo volumio[3446]: error: MPD returned error for command status: Failed to decode "http://listen.shoutcast.com/wwwspotifycom"; Failed to connect to listen.shoutcast.com port 80 after 111 ms: Couldn't connect to server: Couldn't connect to server Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand status took 35 milliseconds Oct 12 11:17:46 rivo volumio[3446]: error: MPD returned error for command status: Failed to decode "http://listen.shoutcast.com/wwwspotifycom"; Failed to connect to listen.shoutcast.com port 80 after 111 ms: Couldn't connect to server: Couldn't connect to server Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand status took 35 milliseconds Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::parseState Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::parseState Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand clearerror took 12 milliseconds Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand clearerror took 11 milliseconds Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 12 11:17:46 rivo volumio[3446]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:17:46 rivo volumio[3446]: verbose: ControllerMpd::parseTrackInfo Oct 12 11:17:46 rivo volumio[3446]: info: ControllerMpd::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::servicePushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:46 rivo volumio[3446]: 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":"wwwspotifycom","artist":"Www.spotify.com","album":null,"uri":"http://listen.shoutcast.com/wwwspotifycom","trackType":""} Oct 12 11:17:46 rivo volumio[3446]: verbose: CURRENT POSITION 0 Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::syncState stateService stop Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio[3446]: info: No code Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio[3446]: info: ControllerMpd::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::servicePushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio[3446]: info: CorePlayQueue::getTrack 0 Oct 12 11:17:46 rivo volumio[3446]: 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":"wwwspotifycom","artist":"Www.spotify.com","album":null,"uri":"http://listen.shoutcast.com/wwwspotifycom","trackType":""} Oct 12 11:17:46 rivo volumio[3446]: verbose: CURRENT POSITION 0 Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::syncState stateService stop Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::syncState currentStatus stop Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio[3446]: info: No code Oct 12 11:17:46 rivo volumio[3446]: info: CoreStateMachine::pushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioPushState Oct 12 11:17:46 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output update for this device Oct 12 11:17:46 rivo volumio[3446]: info: MRS: Pushing multiroomSync output Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.736+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.740+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.741+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.741+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.742+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.742+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.742+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.742+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.742+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" state=STATUS_STOPPED positionMs=251 volume=50 Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.745+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.747+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.748+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.748+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.749+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio5-onboarding[3918]: time=2026-10-12T11:17:46.750+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.214:57594 @ 0x25b8cf0" id= title= Oct 12 11:17:46 rivo volumio[3446]: info: ------------------------------ 130ms Oct 12 11:17:46 rivo volumio[3446]: info: ------------------------------ 131ms Oct 12 11:17:46 rivo volumio[3446]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:17:46 rivo volumio[3446]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:17:46 rivo volumio[3446]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:17:46 rivo volumio[3446]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:17:46 rivo volumio[3446]: error: Failed to parse state for serial connection: TypeError: Cannot read properties of undefined (reading 'toUpperCase') Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:46 rivo volumio[3446]: info: Updating RAAT Signal Path Oct 12 11:17:49 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 12 11:17:49 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:49 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:49 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:49 rivo volumio[3446]: info: Listing playlists Oct 12 11:17:50 rivo volumio[3446]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 12 11:17:50 rivo volumio[3446]: info: CoreCommandRouter::volumioGetState Oct 12 11:17:50 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 11:17:50 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 11:17:50 rivo volumio[3446]: info: Listing playlists Oct 12 11:17:52 rivo volumio[3446]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 12 11:17:52 rivo volumio[3446]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 12 11:17:52 rivo volumio[3446]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:17:52 rivo volumio[3446]: TypeError: Cannot read properties of undefined (reading 'then') Oct 12 11:17:52 rivo volumio[3446]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 12 11:17:52 rivo volumio[3446]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Oct 12 11:17:52 rivo volumio[3446]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Oct 12 11:17:52 rivo volumio[3446]: at Socket.emit (node:events:514:28) Oct 12 11:17:52 rivo volumio[3446]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 12 11:17:52 rivo volumio[3446]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 12 11:17:52 rivo volumio[3446]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 11:17:52 rivo sudo[11109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 11:16' Oct 12 11:17:52 rivo sudo[11109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"