Oct 02 16:47:02 rivo volumio[3242]: info: CALLMETHOD: music_service qobuz saveConfig [object Object] Oct 02 16:47:02 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: qobuz , saveConfig Oct 02 16:47:02 rivo volumio[3242]: info: Saving configuration Oct 02 16:47:02 rivo volumio[3242]: info: Done Oct 02 16:47:04 rivo volumio[3242]: info: CoreCommandRouter::volumioPlay Oct 02 16:47:04 rivo volumio[3242]: info: CoreStateMachine::play index undefined Oct 02 16:47:04 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 16:47:04 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:04 rivo volumio[3242]: info: CoreStateMachine::startPlaybackTimer Oct 02 16:47:04 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:04 rivo volumio[3242]: info: [1790952424377] ControllerQobuz::resume Oct 02 16:47:04 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 16:47:04 rivo volumio[3242]: info: ControllerMpd::resume Oct 02 16:47:04 rivo volumio[3242]: verbose: ControllerMpd::sendMpdCommand play Oct 02 16:47:04 rivo volumio[3242]: info: sendMpdCommand play took 5 milliseconds Oct 02 16:47:08 rivo volumio[3242]: info: CoreCommandRouter::volumioPlay Oct 02 16:47:08 rivo volumio[3242]: info: CoreStateMachine::play index undefined Oct 02 16:47:08 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 16:47:08 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:08 rivo volumio[3242]: info: CoreStateMachine::startPlaybackTimer Oct 02 16:47:08 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:08 rivo volumio[3242]: info: [1790952428945] ControllerQobuz::resume Oct 02 16:47:08 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 16:47:08 rivo volumio[3242]: info: ControllerMpd::resume Oct 02 16:47:08 rivo volumio[3242]: verbose: ControllerMpd::sendMpdCommand play Oct 02 16:47:08 rivo volumio[3242]: info: sendMpdCommand play took 1 milliseconds Oct 02 16:47:10 rivo volumio[3242]: info: CoreCommandRouter::volumioPlay Oct 02 16:47:10 rivo volumio[3242]: info: CoreStateMachine::play index undefined Oct 02 16:47:10 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 16:47:10 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:10 rivo volumio[3242]: info: CoreStateMachine::startPlaybackTimer Oct 02 16:47:10 rivo volumio[3242]: info: CorePlayQueue::getTrack 0 Oct 02 16:47:10 rivo volumio[3242]: info: [1790952430095] ControllerQobuz::resume Oct 02 16:47:10 rivo volumio[3242]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 16:47:10 rivo volumio[3242]: info: ControllerMpd::resume Oct 02 16:47:10 rivo volumio[3242]: verbose: ControllerMpd::sendMpdCommand play Oct 02 16:47:10 rivo volumio[3242]: info: sendMpdCommand play took 6 milliseconds Oct 02 16:47:16 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:16 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:47:16 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 02 16:47:16 rivo sudo[11445]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 02 16:47:16 rivo sudo[11445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:16 rivo sudo[11450]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:47:16 rivo sudo[11450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:16 rivo sudo[11450]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: Error getting the SSID Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo sudo[11455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:47:16 rivo sudo[11455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:16 rivo sudo[11455]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: Error getting the SSID Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo sudo[11445]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:16 rivo sudo[11460]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:47:16 rivo sudo[11460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:16 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 02 16:47:16 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: Error getting the SSID Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 16:47:16 rivo sudo[11460]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:17 rivo sudo[11470]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:47:17 rivo sudo[11470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:17 rivo sudo[11473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:47:17 rivo sudo[11473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:17 rivo sudo[11470]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:17 rivo sudo[11473]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:26 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 16:47:27 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 16:47:27 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: Retrieving Cloud Streaming UI Oct 02 16:47:34 rivo volumio[3242]: info: Getting Tidal Cloud Configuration Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: Getting Qobuz Cloud Configuration Oct 02 16:47:34 rivo volumio[3242]: info: Asking plugin for UI Config Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: Getting Spotify Cloud Configuration Oct 02 16:47:34 rivo volumio[3242]: info: Asking plugin for UI Config Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: Saving Spotify Acccount Oct 02 16:47:34 rivo volumio[3242]: info: Got it Oct 02 16:47:34 rivo volumio[3242]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 16:47:34 rivo volumio[3242]: info: Got Tidal Cloud Configuration Oct 02 16:47:34 rivo volumio[3242]: info: Got it Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:34 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 16:47:38 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:42 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:46 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:51 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:52 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:52.652+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s Oct 02 16:47:52 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:52.739+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=http://pushupdates.volumio.org duration=85.033686ms Oct 02 16:47:52 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:52.810+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=158.382717ms Oct 02 16:47:52 rivo sudo[11593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:47:52 rivo sudo[11593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:52 rivo sudo[11593]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:52 rivo sudo[11595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:47:52 rivo sudo[11595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:47:53 rivo sudo[11595]: pam_unix(sudo:session): session closed for user root Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.010+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://www.googleapis.com duration=356.731762ms Oct 02 16:47:53 rivo volumio[3242]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.14 UA: Mozilla/5.0 (Linux; Android 16; SM-S931B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.044+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=389.042625ms Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.101+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=448.030318ms Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 16:47:53 rivo volumio[3242]: info: Received Get System Info Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:47:53 rivo volumio[3242]: info: Discovery: Getting this device information Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:47:53 rivo volumio[3242]: info: Listing playlists Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.108+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://securetoken.googleapis.com duration=454.995611ms Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.142+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=485.959258ms Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.146+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=http://cddb.volumio.org duration=490.964456ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.200+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://database.volumio.cloud duration=545.559456ms Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.247+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://functions.volumio.cloud duration=591.842822ms Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.247+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://functions.volumio.cloud duration=590.190854ms Oct 02 16:47:53 rivo volumio[3242]: info: Received Get System Version Oct 02 16:47:53 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.286+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=https://google.com duration=632.977739ms Oct 02 16:47:53 rivo volumio5-onboarding[4092]: time=2026-10-02T16:47:53.315+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.14:36850 @ 0x24002d0" latency=42.91486ms timeout=10s endpoint=http://plugins.volumio.org duration=659.943238ms Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:47:54 rivo volumio[3242]: info: Received Get System Info Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:47:54 rivo volumio[3242]: info: Discovery: Getting this device information Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:47:54 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:47:55 rivo volumio[3242]: info: Received Get System Info Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:47:55 rivo volumio[3242]: info: Discovery: Getting this device information Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:47:55 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:48:03 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 16:48:10 rivo volumio[3242]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 16:48:10 rivo volumio[3242]: info: Received Get System Version Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:48:10 rivo volumio[3242]: info: Received Get System Info Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:48:10 rivo volumio[3242]: info: Discovery: Getting this device information Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::volumioGetState Oct 02 16:48:10 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: Retrieving Cloud Streaming UI Oct 02 16:48:21 rivo volumio[3242]: info: Getting Tidal Cloud Configuration Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: Getting Qobuz Cloud Configuration Oct 02 16:48:21 rivo volumio[3242]: info: Asking plugin for UI Config Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: Getting Spotify Cloud Configuration Oct 02 16:48:21 rivo volumio[3242]: info: Asking plugin for UI Config Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: Saving Spotify Acccount Oct 02 16:48:21 rivo volumio[3242]: info: Got it Oct 02 16:48:21 rivo volumio[3242]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 16:48:21 rivo volumio[3242]: info: Got Tidal Cloud Configuration Oct 02 16:48:21 rivo volumio[3242]: info: Got it Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:48:21 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 16:48:25 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:48:25 rivo volumio[3242]: info: Enabling MyMusic plugin bluetooth Oct 02 16:48:25 rivo volumio[3242]: info: Enabling plugin bluetooth Oct 02 16:48:25 rivo volumio[3242]: info: Loading plugin "bluetooth"... Oct 02 16:48:25 rivo volumio[3242]: info: Applying required configuration parameters for plugin bluetooth Oct 02 16:48:25 rivo volumio[3242]: info: Applying required configuration parameters for plugin bluetooth Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 02 16:48:25 rivo volumio[3242]: info: PLUGIN START: bluetooth Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 02 16:48:25 rivo bluetoothd[3392]: Path / reserved for Adv Monitor app :1.26 Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [_watchMediaPlayer] Binding player for path: /org/bluez/hci0/dev_A0_B0_BD_30_AD_BA/mcp/player0 mac: A0:B0:BD:30:AD:BA Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: activePlayer bound: /org/bluez/hci0/dev_A0_B0_BD_30_AD_BA/mcp/player0 Oct 02 16:48:25 rivo bluetoothd[3392]: Adv Monitor app :1.26 disconnected from D-Bus Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 02 16:48:25 rivo sudo[11657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 02 16:48:25 rivo sudo[11657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:48:25 rivo systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 02 16:48:25 rivo sudo[11657]: pam_unix(sudo:session): session closed for user root Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 02 16:48:25 rivo volumio[3242]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 02 16:48:25 rivo volumio[3242]: info: Done. Oct 02 16:48:26 rivo volumiobt[11665]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 02 16:48:26 rivo sudo[11666]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 02 16:48:26 rivo sudo[11666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:48:26 rivo sudo[11666]: pam_unix(sudo:session): session closed for user root Oct 02 16:48:26 rivo sudo[11668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 02 16:48:26 rivo sudo[11668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:48:26 rivo sudo[11668]: pam_unix(sudo:session): session closed for user root Oct 02 16:48:26 rivo volumiobt[11670]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 02 16:48:26 rivo bluetoothd[3392]: Path / reserved for Adv Monitor app :1.28 Oct 02 16:48:26 rivo bluetoothd[3392]: Adv Monitor app :1.28 disconnected from D-Bus Oct 02 16:48:26 rivo volumiobt[11673]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 02 16:48:26 rivo volumiobt[11674]: [195B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [159B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [159B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [159B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [171B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [115B blob data] Oct 02 16:48:26 rivo volumiobt[11674]: [S25 di Carmelo]> discoverable on Oct 02 16:48:26 rivo volumiobt[11674]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 02 16:48:26 rivo volumiobt[11674]: [S25 di Carmelo]> pairable on Oct 02 16:48:26 rivo bluetoothd[3392]: Path / reserved for Adv Monitor app :1.29 Oct 02 16:48:26 rivo bluetoothd[3392]: Adv Monitor app :1.29 disconnected from D-Bus Oct 02 16:48:26 rivo volumiobt[11674]: [S25 di Carmelo]> Oct 02 16:48:26 rivo volumiobt[11675]: INFO [BTSTART] Registering Bluetooth agent... Oct 02 16:48:26 rivo volumiobt[11676]: [NEW] Media /org/bluez/hci0 Oct 02 16:48:26 rivo volumiobt[11676]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11676]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11676]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11676]: [NEW] Player /org/bluez/hci0/dev_A0_B0_BD_30_AD_BA/mcp/player0 [default] Oct 02 16:48:26 rivo bluetoothd[3392]: Path / reserved for Adv Monitor app :1.30 Oct 02 16:48:26 rivo bluetoothd[3392]: Adv Monitor app :1.30 disconnected from D-Bus Oct 02 16:48:26 rivo volumiobt[11677]: No agent is registered Oct 02 16:48:26 rivo volumiobt[11677]: [NEW] Media /org/bluez/hci0 Oct 02 16:48:26 rivo volumiobt[11677]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11677]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11677]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 16:48:26 rivo volumiobt[11677]: [NEW] Player /org/bluez/hci0/dev_A0_B0_BD_30_AD_BA/mcp/player0 [default] Oct 02 16:48:26 rivo bluetoothd[3392]: Path / reserved for Adv Monitor app :1.31 Oct 02 16:48:26 rivo bluetoothd[3392]: Adv Monitor app :1.31 disconnected from D-Bus Oct 02 16:48:26 rivo volumiobt[11678]: INFO [BTSTART] Agent registered successfully. Oct 02 16:48:26 rivo volumiobt[11679]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Connecting to system D-Bus Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Connected to system D-Bus Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Enabled Discoverable mode Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] Agent set as default Oct 02 16:48:27 rivo volumiobt[11680]: 2026-10-02 16:48:27 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 02 16:48:29 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:48:31 rivo volumio[3242]: info: Disabling MyMusic plugin upnp Oct 02 16:48:31 rivo sudo[11699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 02 16:48:31 rivo sudo[11699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:48:31 rivo systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 02 16:48:33 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:48:33 rivo volumio[3242]: info: Enabling MyMusic plugin upnp Oct 02 16:48:33 rivo volumio[3242]: info: Enabling plugin upnp Oct 02 16:48:33 rivo volumio[3242]: info: Loading plugin "upnp"... Oct 02 16:48:33 rivo volumio[3242]: info: [1790952513467] Starting Upmpd Daemon Oct 02 16:48:33 rivo volumio[3242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 16:48:33 rivo volumio[3242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:48:33 rivo volumio[3242]: Error: listen EADDRINUSE: address already in use :::6599 Oct 02 16:48:33 rivo volumio[3242]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 02 16:48:33 rivo volumio[3242]: at listenInCluster (node:net:1920:12) Oct 02 16:48:33 rivo volumio[3242]: at Server.listen (node:net:2008:7) Oct 02 16:48:33 rivo volumio[3242]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 02 16:48:33 rivo volumio[3242]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 02 16:48:33 rivo volumio[3242]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 02 16:48:33 rivo volumio[3242]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 02 16:48:33 rivo volumio[3242]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 02 16:48:33 rivo volumio[3242]: code: 'EADDRINUSE', Oct 02 16:48:33 rivo volumio[3242]: errno: -98, Oct 02 16:48:33 rivo volumio[3242]: syscall: 'listen', Oct 02 16:48:33 rivo volumio[3242]: address: '::', Oct 02 16:48:33 rivo volumio[3242]: port: 6599 Oct 02 16:48:33 rivo volumio[3242]: } Oct 02 16:48:33 rivo volumio[3242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:48:34 rivo sudo[11711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 16:47' Oct 02 16:48:34 rivo sudo[11711]: 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"