-- Logs begin at Sun 2026-08-30 08:48:58 CDT, end at Sun 2026-08-30 08:52:04 CDT. -- Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.901-05:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.915-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=14.505118ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.926-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://securetoken.googleapis.com duration=24.245724ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.939-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=35.968734ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.945-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://google.com duration=43.91281ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.946-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://functions.volumio.cloud duration=44.401182ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.947-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://functions.volumio.cloud duration=44.345141ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.948-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=44.719305ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.950-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://database.volumio.cloud duration=45.408176ms Aug 30 08:51:01 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:01.953-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=49.182653ms Aug 30 08:51:02 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:02.017-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=https://www.googleapis.com duration=116.170652ms Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:02 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:02 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:02 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:02.042-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=http://pushupdates.volumio.org duration=140.024837ms Aug 30 08:51:02 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:02 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:02 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:02.460-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=http://cddb.volumio.org duration=558.842897ms Aug 30 08:51:02 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:02.942-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" latency=145.694464ms timeout=10s endpoint=http://plugins.volumio.org duration=1.040273567s Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:08 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:08 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:08 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:08 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:08 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:08 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:08 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:15 rivoplus volumio[3435]: No protocol specified Aug 30 08:51:15 rivoplus volumio[3435]: xcb_connection_has_error() returned true Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 08:51:15 rivoplus volumio[3435]: No protocol specified Aug 30 08:51:15 rivoplus volumio[3435]: xcb_connection_has_error() returned true Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 08:51:15 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:19 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:19 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:19 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:19 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:19 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:19 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:19 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:29 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:29 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:29 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:29 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:29 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:29 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:29 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:39 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:39 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:39 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:39 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:39 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:39 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:39 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:44 rivoplus volumio[3435]: info: Retrieving Cloud Streaming UI Aug 30 08:51:44 rivoplus volumio[3435]: info: Getting Tidal Cloud Configuration Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:44 rivoplus volumio[3435]: info: Getting Qobuz Cloud Configuration Aug 30 08:51:44 rivoplus volumio[3435]: info: Asking plugin for UI Config Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:44 rivoplus volumio[3435]: info: Getting Spotify Cloud Configuration Aug 30 08:51:44 rivoplus volumio[3435]: info: Asking plugin for UI Config Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:44 rivoplus volumio[3435]: info: Saving Spotify Acccount Aug 30 08:51:44 rivoplus volumio[3435]: info: Got it Aug 30 08:51:44 rivoplus volumio[3435]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 08:51:44 rivoplus volumio[3435]: info: Got Tidal Cloud Configuration Aug 30 08:51:44 rivoplus volumio[3435]: info: Got it Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:51:44 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 08:51:45 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:51:45 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:49 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:49 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:49 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:51:49 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:51:49 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:51:49 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:51:49 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand rescan Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: Aug 30 08:51:53 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand rescan took 17 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: Aug 30 08:51:53 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: Aug 30 08:51:53 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 25 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 78 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 77 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 76 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 74 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 72 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.418-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.418-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:53 rivoplus volumio[3435]: info: ------------------------------ 233ms Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.462-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.463-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.464-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.464-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:53 rivoplus volumio[3435]: info: ------------------------------ 244ms Aug 30 08:51:53 rivoplus volumio[3435]: info: ------------------------------ 242ms Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: Aug 30 08:51:53 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 52 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: info: sendMpdCommand status took 50 milliseconds Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:53 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:53 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:53 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:53 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:53 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.592-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:53 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:53.593-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:53 rivoplus volumio[3435]: info: ------------------------------ 120ms Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand rescan Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: Aug 30 08:51:56 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand rescan took 8 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: Aug 30 08:51:56 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: Aug 30 08:51:56 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 9 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 7 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 7 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 5 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatetrue Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.696-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.697-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.698-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.699-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:56 rivoplus volumio[3435]: info: ------------------------------ 169ms Aug 30 08:51:56 rivoplus volumio[3435]: info: ------------------------------ 166ms Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: Aug 30 08:51:56 rivoplus volumio[3435]: ---------------------------- MPD announces state update: update Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::getState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::sendMpdCommand status Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 170 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 167 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 7 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: info: sendMpdCommand status took 5 milliseconds Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: Command Router : Notfying DB Updatefalse Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::Close All Modals sent Aug 30 08:51:56 rivoplus volumio[3435]: verbose: ControllerMpd::parseState Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:56 rivoplus volumio[3435]: info: ControllerMpd::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::servicePushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreStateMachine::pushState Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: CoreCommandRouter::volumioPushState Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output update for this device Aug 30 08:51:56 rivoplus volumio[3435]: info: MRS: Pushing multiroomSync output Aug 30 08:51:56 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:51:56 rivoplus volumio[3435]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.888-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.888-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.890-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 08:51:56 rivoplus volumio5-onboarding[3944]: time=2026-08-30T08:51:56.890-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.102:55226,192.168.50.102:54756 @ 0x35c0b10" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611385" title="SMOOTH JAZZ DELUXE" Aug 30 08:51:56 rivoplus volumio[3435]: info: ------------------------------ 351ms Aug 30 08:51:56 rivoplus volumio[3435]: info: ------------------------------ 189ms Aug 30 08:51:57 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:52:00 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:52:00 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:52:00 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.188:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 08:52:00 rivoplus volumio[3435]: info: Discovery: Getting this device information Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::volumioGetState Aug 30 08:52:00 rivoplus volumio[3435]: info: CorePlayQueue::getTrack 0 Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 08:52:00 rivoplus volumio[3435]: verbose: New Socket.io Connection to 192.168.50.44:3000 from 192.168.50.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 08:52:00 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 08:52:01 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 08:52:02 rivoplus volumio[3435]: info: Disabling MyMusic plugin upnp Aug 30 08:52:02 rivoplus sudo[5036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 30 08:52:02 rivoplus sudo[5036]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 08:52:02 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 30 08:52:02 rivoplus volumio[3435]: error: Upnp client error: Error: This socket has been ended by the other party Aug 30 08:52:02 rivoplus volumio[3435]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 08:52:03 rivoplus volumio[3435]: info: Enabling MyMusic plugin upnp Aug 30 08:52:03 rivoplus volumio[3435]: info: Enabling plugin upnp Aug 30 08:52:03 rivoplus volumio[3435]: info: Loading plugin "upnp"... Aug 30 08:52:03 rivoplus volumio[3435]: info: [1788097923933] Starting Upmpd Daemon Aug 30 08:52:03 rivoplus volumio[3435]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 08:52:03 rivoplus volumio[3435]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 08:52:03 rivoplus volumio[3435]: Error: listen EADDRINUSE: address already in use :::6599 Aug 30 08:52:03 rivoplus volumio[3435]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Aug 30 08:52:03 rivoplus volumio[3435]: at listenInCluster (net.js:1379:12) Aug 30 08:52:03 rivoplus volumio[3435]: at Server.listen (net.js:1465:7) Aug 30 08:52:03 rivoplus volumio[3435]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 30 08:52:03 rivoplus volumio[3435]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 30 08:52:03 rivoplus volumio[3435]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 30 08:52:03 rivoplus volumio[3435]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 30 08:52:03 rivoplus volumio[3435]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Aug 30 08:52:03 rivoplus volumio[3435]: code: 'EADDRINUSE', Aug 30 08:52:03 rivoplus volumio[3435]: errno: -98, Aug 30 08:52:03 rivoplus volumio[3435]: syscall: 'listen', Aug 30 08:52:03 rivoplus volumio[3435]: address: '::', Aug 30 08:52:03 rivoplus volumio[3435]: port: 6599 Aug 30 08:52:03 rivoplus volumio[3435]: } Aug 30 08:52:03 rivoplus volumio[3435]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 08:52:04 rivoplus sudo[5064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 08:51 Aug 30 08:52:04 rivoplus sudo[5064]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"