Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.451Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.139:49572 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.592Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.139:49572 @ 0x2c00510" latency=53.062249ms platform=PLATFORM_IOS version=6.260930.0 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.593Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.139:49572 @ 0x2c00510" latency=54.149753ms timeout=20s included=[] excluded=[] Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.593Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" Oct 15 17:31:48 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:48 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:48 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.599Z level=INFO msg="emitting device name changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" name=Rivoplus Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.600Z level=INFO msg="emitting device language changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" language=de Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.604Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" timezone=UTC Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.606Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" available=true connected=true macAddress=02:00:00:25:0c:01 ip4Address=192.168.0.153/24 ip6Address= Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.609Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.610Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" setupComplete=true Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 17:31:48 rivoplus volumio[30654]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 15 17:31:48 rivoplus volumio[30654]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 15 17:31:48 rivoplus volumio[30654]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 15 17:31:48 rivoplus volumio[30654]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.792Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" selectedOutputId=5 Oct 15 17:31:48 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:48 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:48 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.826Z level=INFO msg="emitting software info changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" currentVersion=4.208 latestVersion=4.208 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.826Z level=INFO msg="emitting software update progress event" component=server peer="192.168.0.139:49572 @ 0x2c00510" status=UPDATE_STATUS_NONE progress=0 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.827Z level=INFO msg="emitting user changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" userId=M8bfvcOHKRaJFwZgbcIDxqo8O7w1 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.827Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" providers=9 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.827Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" plugins=24 Oct 15 17:31:48 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:48 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.833Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" state=STATUS_PAUSED positionMs=220997 volume=0 Oct 15 17:31:48 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:48.833Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" id="music-library/INTERNAL/RIPPING/Beady Belle/The Best Of Beady Belle/Beady Belle - The Best of Beady Belle - 04 My Name On the World.flac" title="4 - My Name On the World" Oct 15 17:31:49 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:49.603Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.139:49572 @ 0x2c00510" latency=51.681585ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 17:31:49 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:49.622Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s Oct 15 17:31:49 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:49.756Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=http://pushupdates.volumio.org duration=132.673072ms Oct 15 17:31:49 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:49 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:49 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:49 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:49 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:49 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:49 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:49.977Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=355.083433ms Oct 15 17:31:49 rivoplus volumio[30654]: verbose: New Socket.io Connection to 192.168.0.153:3000 from 192.168.0.139 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 15 17:31:50 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 17:31:50 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.009Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=http://cddb.volumio.org duration=382.243075ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.271Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=646.314005ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.275Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=http://plugins.volumio.org duration=650.181062ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.427Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://securetoken.googleapis.com duration=795.52593ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.460Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://www.googleapis.com duration=825.901292ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.495Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=862.664178ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.506Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=883.456962ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.551Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://functions.volumio.cloud duration=924.973365ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.561Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://functions.volumio.cloud duration=927.342ms Oct 15 17:31:50 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:50.653Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://database.volumio.cloud duration=1.02900679s Oct 15 17:31:51 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:31:51.073Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.139:49572 @ 0x2c00510" latency=48.016917ms timeout=10s endpoint=https://google.com duration=1.442769606s Oct 15 17:31:51 rivoplus sudo[9866]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:31:51 rivoplus sudo[9866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:31:51 rivoplus sudo[9866]: pam_unix(sudo:session): session closed for user root Oct 15 17:31:51 rivoplus sudo[9868]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:31:51 rivoplus sudo[9868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:31:51 rivoplus sudo[9868]: pam_unix(sudo:session): session closed for user root Oct 15 17:31:51 rivoplus volumio[30654]: verbose: New Socket.io Connection to 192.168.0.153 from 192.168.0.139 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 15 17:31:51 rivoplus sudo[9874]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:31:51 rivoplus sudo[9874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:31:51 rivoplus sudo[9876]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:31:51 rivoplus sudo[9876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:31:51 rivoplus sudo[9874]: pam_unix(sudo:session): session closed for user root Oct 15 17:31:51 rivoplus sudo[9876]: pam_unix(sudo:session): session closed for user root Oct 15 17:31:51 rivoplus volumio[30654]: verbose: New Socket.io Connection to 192.168.0.153 from 192.168.0.139 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 15 17:31:51 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:52 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetQueue Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreStateMachine::getQueue Oct 15 17:31:52 rivoplus volumio[30654]: info: CorePlayQueue::getQueue Oct 15 17:31:52 rivoplus volumio[30654]: info: Listing playlists Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 17:31:52 rivoplus volumio[30654]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 15 17:31:52 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:52 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:52 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:52 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 17:31:52 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:31:53 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:53 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:53 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:53 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 17:31:54 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 15 17:31:55 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:31:55 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:55 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:55 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:55 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:55 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:55 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:55 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:56 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:31:56 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:56 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:56 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:56 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:57 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:31:57 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:31:57 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:31:57 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:31:57 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:31:57 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:31:57 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:31:57 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:31:58 rivoplus volumio[30654]: info: Executing endpoint metavolumio Oct 15 17:31:58 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 17:31:58 rivoplus volumio[30654]: info: Executing endpoint metavolumio Oct 15 17:31:58 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 17:31:58 rivoplus volumio[30654]: info: Executing endpoint metavolumio Oct 15 17:31:58 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 17:32:01 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: Retrieving Cloud Streaming UI Oct 15 17:32:06 rivoplus volumio[30654]: info: Getting Tidal Cloud Configuration Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: Getting Qobuz Cloud Configuration Oct 15 17:32:06 rivoplus volumio[30654]: info: Asking plugin for UI Config Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: Getting Spotify Cloud Configuration Oct 15 17:32:06 rivoplus volumio[30654]: info: Asking plugin for UI Config Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: Saving Spotify Acccount Oct 15 17:32:06 rivoplus volumio[30654]: info: Got it Oct 15 17:32:06 rivoplus volumio[30654]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 15 17:32:06 rivoplus volumio[30654]: info: Got it Oct 15 17:32:06 rivoplus volumio[30654]: info: Got Tidal Cloud Configuration Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetBrowseSources Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetBrowseSources Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetBrowseSources Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 17:32:06 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 17:32:09 rivoplus volumio[30654]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 15 17:32:09 rivoplus volumio[30654]: info: Received Get System Version Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 17:32:09 rivoplus volumio[30654]: info: Received Get System Info Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:32:09 rivoplus volumio[30654]: info: Discovery: Getting this device information Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::volumioGetState Oct 15 17:32:09 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 3 Oct 15 17:32:09 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:32:10 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 17:32:13 rivoplus volumio[30654]: info: Disabling MyMusic plugin bluetooth Oct 15 17:32:13 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: [FUNC] onStop Oct 15 17:32:13 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Oct 15 17:32:13 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Oct 15 17:32:13 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Oct 15 17:32:13 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Oct 15 17:32:13 rivoplus sudo[9960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Oct 15 17:32:13 rivoplus sudo[9960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:13 rivoplus systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 15 17:32:13 rivoplus systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Oct 15 17:32:13 rivoplus volumiobt[9978]: INFO [BTSTART] Disconnecting all Bluetooth devices... Oct 15 17:32:13 rivoplus bluetoothd[3413]: Adv Monitor app :1.775 disconnected from D-Bus Oct 15 17:32:13 rivoplus volumiobt[9990]: INFO [BTSTART] Unregistering Bluetooth agent... Oct 15 17:32:13 rivoplus volumiobt[9991]: No agent is registered Oct 15 17:32:13 rivoplus volumiobt[9991]: [NEW] Media /org/bluez/hci0 Oct 15 17:32:13 rivoplus volumiobt[9991]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 15 17:32:13 rivoplus volumiobt[9991]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 15 17:32:13 rivoplus volumiobt[9991]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 15 17:32:13 rivoplus bluetoothd[3413]: Adv Monitor app :1.776 disconnected from D-Bus Oct 15 17:32:13 rivoplus volumiobt[9996]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Oct 15 17:32:13 rivoplus volumiobt[9998]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Oct 15 17:32:14 rivoplus volumiobt[3964]: Terminated Oct 15 17:32:14 rivoplus systemd[1]: volumiobt.service: Main process exited, code=exited, status=143/n/a Oct 15 17:32:14 rivoplus systemd[1]: volumiobt.service: Failed with result 'exit-code'. Oct 15 17:32:14 rivoplus systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 15 17:32:14 rivoplus sudo[9960]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:14 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Oct 15 17:32:14 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::resetVolumioState Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::getcurrentVolume Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 17:32:14 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::volumioStop Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::stop Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 17:32:14 rivoplus bluetoothd[3413]: Path / reserved for Adv Monitor app :1.777 Oct 15 17:32:14 rivoplus volumio[30654]: info: VolumeController:: Volume=0 Mute =false Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::pushState Oct 15 17:32:14 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 0 Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::volumioPushState Oct 15 17:32:14 rivoplus bluetoothd[3413]: Adv Monitor app :1.777 disconnected from D-Bus Oct 15 17:32:14 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output update for this device Oct 15 17:32:14 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::updateTrackBlock Oct 15 17:32:14 rivoplus volumio[30654]: info: CorePlayQueue::getTrackBlock Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 17:32:14 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:14.307Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" state=STATUS_STOPPED positionMs=0 volume=0 Oct 15 17:32:14 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:14.308Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" id="music-library/INTERNAL/RIPPING/Beady Belle/The Best Of Beady Belle/Beady Belle - The Best of Beady Belle - 01 Saved.flac" title="1 - Saved" Oct 15 17:32:14 rivoplus volumio[30654]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered off Oct 15 17:32:14 rivoplus volumio[30654]: info: Disabling plugin bluetooth Oct 15 17:32:14 rivoplus volumio[30654]: info: Done. Oct 15 17:32:14 rivoplus volumio[30654]: info: VolumeController:: Volume=0 Mute =false Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreStateMachine::pushState Oct 15 17:32:14 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 0 Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::volumioPushState Oct 15 17:32:14 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output update for this device Oct 15 17:32:14 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output Oct 15 17:32:14 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:14.473Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" state=STATUS_STOPPED positionMs=0 volume=0 Oct 15 17:32:14 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:14.473Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" id="music-library/INTERNAL/RIPPING/Beady Belle/The Best Of Beady Belle/Beady Belle - The Best of Beady Belle - 01 Saved.flac" title="1 - Saved" Oct 15 17:32:14 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 17:32:15 rivoplus volumio[30654]: info: Disabling MyMusic plugin multiroom Oct 15 17:32:15 rivoplus volumio[30654]: info: Disabling plugin multiroom Oct 15 17:32:15 rivoplus volumio[30654]: info: Preparing to generate the ALSA configuration file Oct 15 17:32:15 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 15 17:32:15 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 15 17:32:15 rivoplus volumio[30654]: info: Asound.conf file written Oct 15 17:32:15 rivoplus sudo[10034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 15 17:32:15 rivoplus sudo[10034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:15 rivoplus sudo[10034]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:15 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 15 17:32:15 rivoplus kernel: Err: can not set rate to 4274967296! Oct 15 17:32:15 rivoplus kernel: Range[3920000 - 500000000] Oct 15 17:32:15 rivoplus kernel: out of value, fixed it Oct 15 17:32:15 rivoplus kernel: id=0 set inskew=0 Oct 15 17:32:15 rivoplus volumio[30654]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 17:32:15 rivoplus volumio[30654]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 17:32:15 rivoplus volumio[30654]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 15 17:32:15 rivoplus volumio[30654]: Hardware is initialized using a generic method Oct 15 17:32:15 rivoplus volumio[30654]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 15 17:32:15 rivoplus volumio[30654]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 15 17:32:15 rivoplus volumio[30654]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 15 17:32:15 rivoplus volumio[30654]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 15 17:32:15 rivoplus volumio[30654]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 15 17:32:15 rivoplus volumio[30654]: info: Output device has changed, restarting MPD Oct 15 17:32:15 rivoplus volumio[30654]: info: Output device has changed, restarting Shairport Sync Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 17:32:15 rivoplus sudo[10039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 17:32:15 rivoplus sudo[10039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:15 rivoplus sudo[10039]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:15 rivoplus sudo[10042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 17:32:15 rivoplus sudo[10042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:15 rivoplus volumio[30654]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 17:32:15 rivoplus volumio[30654]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: Done. Oct 15 17:32:15 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 17:32:15 rivoplus volumio[30654]: info: MPD Permissions set Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 17:32:15 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 17:32:16 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 17:32:16 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 15 17:32:16 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 17:32:16 rivoplus volumio[30654]: error: Upnp client error: Error: read ECONNRESET Oct 15 17:32:16 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 15 17:32:16 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 17:32:16 rivoplus volumio[30654]: info: VolumeController:: External volume change detected: vol=41 mute=false Oct 15 17:32:16 rivoplus volumio[30654]: info: CoreStateMachine::pushState Oct 15 17:32:16 rivoplus volumio[30654]: info: CorePlayQueue::getTrack 0 Oct 15 17:32:16 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 17:32:16 rivoplus volumio[30654]: info: CoreCommandRouter::volumioPushState Oct 15 17:32:16 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 17:32:16 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output update for this device Oct 15 17:32:16 rivoplus volumio[30654]: info: MRS: Pushing multiroomSync output Oct 15 17:32:16 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:16.098Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" state=STATUS_STOPPED positionMs=0 volume=41 Oct 15 17:32:16 rivoplus volumio5-onboarding[4629]: time=2026-10-15T17:32:16.099Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49572 @ 0x2c00510" id="music-library/INTERNAL/RIPPING/Beady Belle/The Best Of Beady Belle/Beady Belle - The Best of Beady Belle - 01 Saved.flac" title="1 - Saved" Oct 15 17:32:16 rivoplus volumio[30654]: info: Starting Shairport Sync Oct 15 17:32:16 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 17:32:16 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 17:32:16 rivoplus sudo[10059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 17:32:16 rivoplus sudo[10059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:16 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 17:32:16 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 17:32:16 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 17:32:16 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 17:32:16 rivoplus sudo[10059]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:16 rivoplus volumio[30654]: info: Shairport-Sync Started Oct 15 17:32:16 rivoplus sudo[10060]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 17:32:16 rivoplus sudo[10060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 17:32:16 rivoplus sudo[10060]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:17 rivoplus volumio[30654]: info: Disabling MyMusic plugin upnp Oct 15 17:32:17 rivoplus sudo[10081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 15 17:32:17 rivoplus sudo[10081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:32:17 rivoplus systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 15 17:32:18 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 15 17:32:22 rivoplus mpd[10079]: 2026-10-15T17:32:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 17:32:22 rivoplus volumio[30654]: info: Enabling MyMusic plugin upnp Oct 15 17:32:22 rivoplus volumio[30654]: info: Enabling plugin upnp Oct 15 17:32:22 rivoplus volumio[30654]: info: Loading plugin "upnp"... Oct 15 17:32:22 rivoplus volumio[30654]: info: [1792085542534] Starting Upmpd Daemon Oct 15 17:32:22 rivoplus volumio[30654]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 17:32:22 rivoplus volumio[30654]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:32:22 rivoplus volumio[30654]: Error: listen EADDRINUSE: address already in use :::6599 Oct 15 17:32:22 rivoplus volumio[30654]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 15 17:32:22 rivoplus volumio[30654]: at listenInCluster (node:net:1920:12) Oct 15 17:32:22 rivoplus volumio[30654]: at Server.listen (node:net:2008:7) Oct 15 17:32:22 rivoplus volumio[30654]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 15 17:32:22 rivoplus volumio[30654]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 15 17:32:22 rivoplus volumio[30654]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 15 17:32:22 rivoplus volumio[30654]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 15 17:32:22 rivoplus volumio[30654]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 15 17:32:22 rivoplus volumio[30654]: code: 'EADDRINUSE', Oct 15 17:32:22 rivoplus volumio[30654]: errno: -98, Oct 15 17:32:22 rivoplus volumio[30654]: syscall: 'listen', Oct 15 17:32:22 rivoplus volumio[30654]: address: '::', Oct 15 17:32:22 rivoplus volumio[30654]: port: 6599 Oct 15 17:32:22 rivoplus volumio[30654]: } Oct 15 17:32:22 rivoplus volumio[30654]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:32:22 rivoplus systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 17:32:22 rivoplus sudo[10042]: pam_unix(sudo:session): session closed for user root Oct 15 17:32:24 rivoplus sudo[10112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 17:31' Oct 15 17:32:24 rivoplus sudo[10112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"