-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Tue 2025-12-30 15:33:57 JST. -- Dec 30 15:32:01 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 15:32:01 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:01 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:01 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:01 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:01 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:01 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:01 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:03 rivoplus volumio-remote-updater[2861]: No test mode Dec 30 15:32:03 rivoplus volumio-remote-updater[2861]: No alpha test mode Dec 30 15:32:04 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 30 15:32:04 rivoplus volumio[3220]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 30 15:32:04 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 30 15:32:04 rivoplus volumio[3220]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Dec 30 15:32:04 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:04.978Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:05 rivoplus volumio[3220]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-dy67rxw8 Dec 30 15:32:05 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:05.008Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:05 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 30 15:32:05 rivoplus volumio[3220]: info: CoreCommandRouter::Close All Modals sent Dec 30 15:32:08 rivoplus volumio[3220]: info: Collecting PyYAML (from camilladsp==1.0.0) Dec 30 15:32:08 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:08.017Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:09 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Dec 30 15:32:09 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:09.566Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:10 rivoplus volumio[3220]: info: Installing build dependencies: started Dec 30 15:32:10 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:10.557Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 15:32:12 rivoplus volumio[3220]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 15:32:12 rivoplus volumio[3220]: info: Received Get System Version Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:32:12 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:12 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:12 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:12 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 15:32:13 rivoplus volumio[3220]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 15:32:13 rivoplus volumio[3220]: info: Received Get System Version Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:32:13 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:13 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:13 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:13 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 30 15:32:15 rivoplus volumio[3220]: info: Received Get System Version Dec 30 15:32:15 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:32:18 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:18 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 15:32:18 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 15:32:18 rivoplus sudo[7572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 15:32:18 rivoplus sudo[7572]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus sudo[7572]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:18 rivoplus sudo[7578]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Dec 30 15:32:18 rivoplus sudo[7578]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:18 rivoplus sudo[7585]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Dec 30 15:32:18 rivoplus sudo[7585]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:18 rivoplus sudo[7591]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Dec 30 15:32:18 rivoplus sudo[7591]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:32:18 rivoplus sudo[7599]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 15:32:18 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 15:32:18 rivoplus sudo[7599]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:18 rivoplus sudo[7602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:18 rivoplus sudo[7602]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:18 rivoplus sudo[7608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 15:32:18 rivoplus sudo[7608]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:19 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 8 Dec 30 15:32:21 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04befd000 drv_status 0x0 e_type 69 e_states 0 Dec 30 15:32:22 rivoplus sudo[7608]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:22 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:22 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:22 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 15:32:22 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:22.527Z level=INFO msg="BLE descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName=Rivoplus deviceModel=0x24267f8 Dec 30 15:32:22 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:22.532Z level=INFO msg="mDNS descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName=Rivoplus deviceModel=0x24267f8 Dec 30 15:32:22 rivoplus volumio[3220]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 30 15:32:22 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 30 15:32:22 rivoplus sudo[7611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 30 15:32:22 rivoplus sudo[7611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:23 rivoplus sudo[7611]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:23 rivoplus sudo[7613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 30 15:32:23 rivoplus sudo[7613]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:23 rivoplus sudo[7613]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:23 rivoplus volumio[3220]: info: Permissions for /etc/network/interfaces set Dec 30 15:32:23 rivoplus volumio[3220]: info: Restarting Wireless Network Dec 30 15:32:23 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 30 15:32:23 rivoplus volumio[3220]: info: Discovery: Restarting Advertising due to device name change Dec 30 15:32:23 rivoplus sudo[7616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 30 15:32:23 rivoplus sudo[7616]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:23 rivoplus systemd[1]: Starting Wireless Services... Dec 30 15:32:23 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:23 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 30 15:32:23 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 15:32:23 rivoplus systemd[1]: systemd-timedated.service: Succeeded. Dec 30 15:32:23 rivoplus volumio-remote-updater[2861]: No test mode Dec 30 15:32:23 rivoplus volumio-remote-updater[2861]: No alpha test mode Dec 30 15:32:23 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 30 15:32:23 rivoplus volumio[3220]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 30 15:32:24 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 30 15:32:24 rivoplus wireless.js[7618]: Cleaning previous... Dec 30 15:32:24 rivoplus volumio[3220]: info: Discovery: A device disappeared from network Dec 30 15:32:24 rivoplus volumio[3220]: info: Discovery: Device rivoplus disappeared from network Dec 30 15:32:24 rivoplus volumio[3220]: info: Discovery: A device disappeared from network Dec 30 15:32:24 rivoplus systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Dec 30 15:32:24 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:24 rivoplus dnsmasq[4835]: exiting on receipt of SIGTERM Dec 30 15:32:24 rivoplus systemd[1]: dnsmasq.service: Succeeded. Dec 30 15:32:24 rivoplus systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Dec 30 15:32:24 rivoplus systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 30 15:32:24 rivoplus kernel: wl_cfg80211_del_station: Disconnect STA : ff:ff:ff:ff:ff:ff scb_val.val 3 Dec 30 15:32:24 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:24 rivoplus kernel: wl_iw_event: Link Down with b8:13:32:e4:3f:ce, reason=4 Dec 30 15:32:24 rivoplus kernel: CFG80211-ERROR) wl_bss_iovar_war : wl bss 2 Dec 30 15:32:24 rivoplus systemd[1]: hostapd.service: Succeeded. Dec 30 15:32:24 rivoplus systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 30 15:32:24 rivoplus sudo[7667]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 30 15:32:24 rivoplus sudo[7667]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.211.176. Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Withdrawing address record for 169.254.211.176 on wlan0. Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.211.176. Dec 30 15:32:24 rivoplus avahi-daemon[2867]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 30 15:32:24 rivoplus sudo[7667]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:24 rivoplus sudo[7670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 30 15:32:24 rivoplus sudo[7670]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:24 rivoplus kernel: dhd_stop: Enter ffffffc04befd000 Dec 30 15:32:24 rivoplus kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Dec 30 15:32:24 rivoplus kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Dec 30 15:32:24 rivoplus kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Dec 30 15:32:24 rivoplus kernel: wl_android_wifi_off in 1 Dec 30 15:32:24 rivoplus kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Dec 30 15:32:24 rivoplus kernel: dhd_dbg_detach_pkt_monitor, 2204 Dec 30 15:32:24 rivoplus kernel: dhd_bus_devreset: == Power OFF == Dec 30 15:32:24 rivoplus kernel: bcmsdh_oob_intr_unregister: Enter Dec 30 15:32:24 rivoplus kernel: dhd_txglom_enable: enable 0 Dec 30 15:32:24 rivoplus kernel: dhd_conf_set_txglom_params: txglom_mode=copy Dec 30 15:32:24 rivoplus kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Dec 30 15:32:24 rivoplus kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Dec 30 15:32:24 rivoplus kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Dec 30 15:32:24 rivoplus kernel: dhd_bus_devreset: WLAN OFF DONE Dec 30 15:32:24 rivoplus kernel: wifi_platform_set_power = 0 Dec 30 15:32:24 rivoplus kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Dec 30 15:32:24 rivoplus kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Dec 30 15:32:24 rivoplus dhcpcd[3576]: wlan0: carrier lost Dec 30 15:32:24 rivoplus kernel: wl_android_wifi_off out Dec 30 15:32:24 rivoplus kernel: wl_ext_iapsta_dettach_netdev: Enter Dec 30 15:32:24 rivoplus kernel: dhd_stop: Exit Dec 30 15:32:24 rivoplus sudo[7670]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:24 rivoplus dhcpcd[3576]: wlan0: deleting route to 169.254.0.0/16 Dec 30 15:32:24 rivoplus wireless.js[7618]: Stopped aP Dec 30 15:32:24 rivoplus wireless.js[7618]: Wireless Networking DISABLED, not starting wireless flow Dec 30 15:32:24 rivoplus iwconfig[7720]: Error for wireless request "Set Power Management" (8B2C) : Dec 30 15:32:24 rivoplus iwconfig[7720]: SET failed on device wlan0 ; Invalid argument. Dec 30 15:32:24 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:24 rivoplus systemd[1]: wireless.service: Succeeded. Dec 30 15:32:24 rivoplus systemd[1]: Started Wireless Services. Dec 30 15:32:24 rivoplus sudo[7616]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:25 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:25 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:25 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 15:32:25 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 15:32:25 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:25.259Z level=INFO msg="BLE descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName=Rivoplus deviceModel=0x29fa420 Dec 30 15:32:25 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:25.263Z level=INFO msg="mDNS descriptor updated" deviceId=22366d4cda9f547124971b6f96d9907a deviceName=Rivoplus deviceModel=0x29fa420 Dec 30 15:32:26 rivoplus ntpd[3575]: Deleting interface #6 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=250 secs Dec 30 15:32:26 rivoplus ntpd[3575]: Deleting interface #7 wlan0, 169.254.211.176#123, interface stats: received=0, sent=0, dropped=0, active_time=237 secs Dec 30 15:32:28 rivoplus volumio[3220]: info: Discovery: Started advertising with name: Rivoplus Dec 30 15:32:29 rivoplus volumio[3220]: info: Discovery: adding f2d29d31-03de-4527-b5d5-eb1b977a9ca1 Dec 30 15:32:29 rivoplus volumio[3220]: info: Discovery: Found device Rivoplus Dec 30 15:32:29 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:29 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:29 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 30 15:32:29 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 30 15:32:30 rivoplus sudo[7790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:30 rivoplus sudo[7790]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:30 rivoplus sudo[7790]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:34 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 30 15:32:35 rivoplus volumio[3220]: info: Refreshing Cached IP Addresses Dec 30 15:32:35 rivoplus sudo[7812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 30 15:32:35 rivoplus sudo[7812]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:32:35 rivoplus sudo[7815]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7812]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7815]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7819]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus systemd[1]: upmpdcli.service: Succeeded. Dec 30 15:32:35 rivoplus sudo[7819]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:32:35 rivoplus sudo[7823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7823]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7826]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7826]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus volumio[3220]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:35 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 15:32:35 rivoplus volumio[3220]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 30 15:32:35 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:35 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:35 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:35 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:35 rivoplus volumio[3220]: info: Listing playlists Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 15:32:35 rivoplus sudo[7833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 15:32:35 rivoplus sudo[7833]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7833]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7839]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7839]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: Error getting the SSID Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus sudo[7846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7846]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7846]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: Error getting the SSID Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus sudo[7852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: Error getting the SSID Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:32:35 rivoplus sudo[7852]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:32:35 rivoplus sudo[7858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 15:32:35 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 15:32:35 rivoplus sudo[7858]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:35 rivoplus sudo[7862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:35 rivoplus sudo[7862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:35 rivoplus sudo[7862]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:36 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 15:32:37 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:37 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:37 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 15:32:37 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:37 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:37 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:37 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:38 rivoplus volumio[3220]: info: Installing build dependencies: finished with status 'done' Dec 30 15:32:38 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:38.376Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:42 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:42.052Z level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=1 Dec 30 15:32:42 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:42.224Z level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=0100 addr=1 Dec 30 15:32:44 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:32:44 rivoplus volumio[3220]: info: CoreStateMachine::getQueue Dec 30 15:32:44 rivoplus volumio[3220]: info: CorePlayQueue::getQueue Dec 30 15:32:45 rivoplus sudo[7887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:32:45 rivoplus sudo[7887]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:45 rivoplus sudo[7887]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:45 rivoplus sudo[7890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:32:45 rivoplus sudo[7890]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:45 rivoplus sudo[7890]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:45 rivoplus sudo[7897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 15:32:45 rivoplus sudo[7897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:45 rivoplus systemd[1]: Started UPnP Renderer front-end to MPD. Dec 30 15:32:45 rivoplus sudo[7897]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:45 rivoplus volumio[3220]: info: Upmpdcli Daemon Started Dec 30 15:32:45 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 30 15:32:45 rivoplus volumio[7899]: writing RSA key Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: Retrieving Cloud Streaming UI Dec 30 15:32:47 rivoplus volumio[3220]: info: Getting Tidal Cloud Configuration Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: Getting Qobuz Cloud Configuration Dec 30 15:32:47 rivoplus volumio[3220]: info: Asking plugin for UI Config Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: Getting Spotify Cloud Configuration Dec 30 15:32:47 rivoplus volumio[3220]: info: Asking plugin for UI Config Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: Saving Spotify Acccount Dec 30 15:32:47 rivoplus volumio[3220]: info: Got it Dec 30 15:32:47 rivoplus volumio[3220]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 15:32:47 rivoplus volumio[3220]: info: Got Tidal Cloud Configuration Dec 30 15:32:47 rivoplus volumio[3220]: info: Got it Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:32:47 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 15:32:49 rivoplus volumio[3220]: info: Collecting websocket_client (from camilladsp==1.0.0) Dec 30 15:32:49 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:49.075Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:49 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Dec 30 15:32:49 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:49.420Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:49 rivoplus volumio[3220]: info: Building wheels for collected packages: camilladsp, PyYAML Dec 30 15:32:49 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:49.585Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:49 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for camilladsp: started Dec 30 15:32:49 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:49.610Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:50 rivoplus volumio[3220]: info: Disabling MyMusic plugin bluetooth Dec 30 15:32:50 rivoplus ntpd[3575]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 30 15:32:50 rivoplus sudo[7946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Dec 30 15:32:50 rivoplus sudo[7946]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:50 rivoplus systemd[1]: Stopping Volumio Bluetooth Module... Dec 30 15:32:50 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:50 rivoplus volumiobt[7949]: Killing pulse Dec 30 15:32:50 rivoplus volumiobt[7949]: Killing BT Pair agent Dec 30 15:32:50 rivoplus bluetoothd[3450]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSource Dec 30 15:32:50 rivoplus bluetoothd[3450]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSink Dec 30 15:32:50 rivoplus systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Dec 30 15:32:50 rivoplus systemd[1]: volumiobt.service: Succeeded. Dec 30 15:32:50 rivoplus systemd[1]: Stopped Volumio Bluetooth Module. Dec 30 15:32:50 rivoplus sudo[7946]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:50 rivoplus volumio[3220]: info: Volumio BT Module successfully stopped Dec 30 15:32:50 rivoplus volumio[3220]: info: Disabling plugin bluetooth Dec 30 15:32:50 rivoplus volumio[3220]: info: Done. Dec 30 15:32:50 rivoplus volumio[3220]: info: Disabling MyMusic plugin multiroom Dec 30 15:32:50 rivoplus volumio[3220]: info: Disabling plugin multiroom Dec 30 15:32:50 rivoplus volumio[3220]: info: Preparing to generate the ALSA configuration file Dec 30 15:32:51 rivoplus volumio[3220]: info: Asound.conf file written Dec 30 15:32:51 rivoplus sudo[7953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 15:32:51 rivoplus sudo[7953]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus sudo[7953]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus volumio[3220]: No protocol specified Dec 30 15:32:51 rivoplus volumio[3220]: xcb_connection_has_error() returned true Dec 30 15:32:51 rivoplus volumio[3220]: No state is present for card AMLAUGESOUNDMP1 Dec 30 15:32:51 rivoplus volumio[3220]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Dec 30 15:32:51 rivoplus volumio[3220]: Hardware is initialized using a generic method Dec 30 15:32:51 rivoplus volumio[3220]: No state is present for card AMLAUGESOUNDMP1 Dec 30 15:32:51 rivoplus volumio[3220]: No state is present for card Device Dec 30 15:32:51 rivoplus volumio[3220]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Dec 30 15:32:51 rivoplus volumio[3220]: Hardware is initialized using a generic method Dec 30 15:32:51 rivoplus volumio[3220]: No state is present for card Device Dec 30 15:32:51 rivoplus volumio[3220]: info: Output device has changed, restarting MPD Dec 30 15:32:51 rivoplus sudo[7959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 15:32:51 rivoplus sudo[7959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus volumio[3220]: info: Output device has changed, restarting Shairport Sync Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:32:51 rivoplus sudo[7959]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus sudo[7962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 15:32:51 rivoplus sudo[7962]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 15:32:51 rivoplus volumio[3220]: No protocol specified Dec 30 15:32:51 rivoplus volumio[3220]: xcb_connection_has_error() returned true Dec 30 15:32:51 rivoplus volumio[3220]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 15:32:51 rivoplus volumio[3220]: No protocol specified Dec 30 15:32:51 rivoplus volumio[3220]: xcb_connection_has_error() returned true Dec 30 15:32:51 rivoplus volumio[3220]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 15:32:51 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 15:32:51 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 15:32:51 rivoplus volumio[3220]: info: QobuzConnect: setDeactiveState invoked Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:51 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:51 rivoplus volumio[3220]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus vtcs[5595]: [2025-12-30 15:32:51.371] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 15:32:51 rivoplus vtcs[5595]: [2025-12-30 15:32:51.369] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 15:32:51 rivoplus volumio[3220]: info: Done. Dec 30 15:32:51 rivoplus sudo[7978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 15:32:51 rivoplus sudo[7978]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus sudo[7980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 15:32:51 rivoplus sudo[7980]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus sudo[7968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 15:32:51 rivoplus sudo[7968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 30 15:32:51 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 30 15:32:51 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 30 15:32:51 rivoplus sudo[7968]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 30 15:32:51 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:51 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 15:32:51 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:51 rivoplus sudo[7989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 15:32:51 rivoplus sudo[7989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus sudo[7978]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus volumio[3220]: info: MPD Permissions set Dec 30 15:32:51 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Dec 30 15:32:51 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:51.672Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:51 rivoplus volumio[3220]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-8j310xm4/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Dec 30 15:32:51 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for PyYAML: started Dec 30 15:32:51 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:51.700Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:51 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:51.701Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:51 rivoplus sudo[7980]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus sudo[7989]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus sudo[7995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 15:32:51 rivoplus sudo[7995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 15:32:51 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 15:32:51 rivoplus qobuz-connect[5528]: 20251230 15:32:51.834 [5528.5528] INFO SampleApp: Stopping Local configuration server Dec 30 15:32:51 rivoplus qobuz-connect[5528]: 20251230 15:32:51.845 [5528.5528] INFO SampleApp: shat down connection on UNIX socket Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:51 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 15:32:51 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 15:32:51 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 30 15:32:51 rivoplus sudo[7995]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:32:51 rivoplus volumio[3220]: info: Not Reporting Auto name since its the default one Dec 30 15:32:52 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:32:52 rivoplus volumio[3220]: error: MPD error: Error: This socket has been ended by the other party Dec 30 15:32:52 rivoplus volumio[3220]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 15:32:52 rivoplus volumio[3220]: error: MPD error: Error: This socket has been ended by the other party Dec 30 15:32:52 rivoplus volumio[3220]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 15:32:52 rivoplus volumio[3220]: error: MPD error: Error: This socket has been ended by the other party Dec 30 15:32:52 rivoplus volumio[3220]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 30 15:32:52 rivoplus volumio[3220]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 15:32:52 rivoplus volumio[3220]: info: QobuzConnect: setDeactiveState invoked Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:52 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:52 rivoplus volumio[3220]: info: Starting Shairport Sync Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 30 15:32:52 rivoplus sudo[8014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 15:32:52 rivoplus sudo[8014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:52 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 15:32:52 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 30 15:32:52 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 15:32:52 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 30 15:32:52 rivoplus volumio[3220]: No protocol specified Dec 30 15:32:52 rivoplus volumio[3220]: xcb_connection_has_error() returned true Dec 30 15:32:52 rivoplus sudo[8014]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:52 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:52 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 15:32:52 rivoplus sudo[8022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 30 15:32:52 rivoplus sudo[8022]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:52 rivoplus volumio[3220]: info: Executing endpoint qc_getconfig Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 30 15:32:52 rivoplus sudo[8022]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.355 [8006.8006] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 30 15:32:52 rivoplus sudo[8027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 30 15:32:52 rivoplus sudo[8027]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:52 rivoplus volumio[3220]: info: Shairport-Sync Started Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.381 [8006.8006] INFO VolumeManager: [0xab832240]: Setting new playback volume: 75 Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.382 [8006.8006] INFO VolumeManager: [0xab832240]: Setting new mute state: 0 Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.383 [8006.8006] INFO QobuzConnect: [0xab832c10]: Client initialized! Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.383 [8006.8006] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 30 15:32:52 rivoplus volumio[3220]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 30 15:32:52 rivoplus volumio[3220]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 30 15:32:52 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 15:32:52 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 15:32:52 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 15:32:52 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 15:32:52 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:52 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.430 [8006.8006] INFO LocalConfigManager: [0xab831b20]: Starting Local Configuration server Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.430 [8006.8006] INFO SampleApp: Starting Local configuration server Dec 30 15:32:52 rivoplus systemd[1]: Started RAAT DAEMON. Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.434 [8006.8006] INFO SampleApp: Connected to UNIX socket client 0xab827ed8 Dec 30 15:32:52 rivoplus sudo[8027]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:52 rivoplus volumio[3220]: info: Raat Daemon started successfully Dec 30 15:32:52 rivoplus qobuz-connect[8006]: 20251230 15:32:52.504 [8006.8006] INFO SampleApp: Playback volume changed: 75 Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:52 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:52 rivoplus volumio[3220]: info: Executing endpoint restartRAATSocket Dec 30 15:32:52 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 30 15:32:52 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:52 rivoplus volumio[3220]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 15:32:53 rivoplus volumio[3220]: info: Disabling MyMusic plugin upnp Dec 30 15:32:53 rivoplus sudo[8046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 30 15:32:53 rivoplus sudo[8046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:53 rivoplus volumio[3220]: error: Upnp client error: Error: This socket has been ended by the other party Dec 30 15:32:53 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 30 15:32:53 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 15:32:53 rivoplus volumio[3220]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 15:32:53 rivoplus volumio[3220]: info: Received Get System Version Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:32:53 rivoplus volumio[3220]: info: Received Get System Info Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:32:53 rivoplus volumio[3220]: info: Discovery: Getting this device information Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:53 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:53 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:32:53 rivoplus volumio[3220]: info: Disabling MyMusic plugin metavolumio Dec 30 15:32:53 rivoplus volumio[3220]: info: Removing METAVOLUMIO REST API Endpoints Dec 30 15:32:53 rivoplus volumio[3220]: info: Removing metavolumio REST Endpoint Dec 30 15:32:53 rivoplus volumio[3220]: info: Removing getSimilarArtists REST Endpoint Dec 30 15:32:53 rivoplus volumio[3220]: info: Removing getSimilarAlbums REST Endpoint Dec 30 15:32:53 rivoplus volumio[3220]: info: Removing getSimilarTracks REST Endpoint Dec 30 15:32:53 rivoplus volumio[3220]: info: Disabling plugin metavolumio Dec 30 15:32:53 rivoplus volumio[3220]: info: Done. Dec 30 15:32:53 rivoplus mpd[7990]: Dec 30 15:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 15:32:53 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 15:32:53 rivoplus sudo[7962]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:54 rivoplus volumio[3220]: error: updateQueue error: null Dec 30 15:32:54 rivoplus nmbd[3088]: [2025/12/30 15:32:54.103744, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 30 15:32:54 rivoplus nmbd[3088]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Dec 30 15:32:54 rivoplus nmbd[3088]: [2025/12/30 15:32:54.104292, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 30 15:32:54 rivoplus nmbd[3088]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 30 15:32:54 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' Dec 30 15:32:54 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:54.652Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:54 rivoplus volumio[3220]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 Dec 30 15:32:54 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:54.670Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:54 rivoplus volumio[3220]: info: TidalConnect service stoped! Dec 30 15:32:54 rivoplus volumio[3220]: info: TidalConnect service stoped! Dec 30 15:32:54 rivoplus volumio[3220]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 15:32:54 rivoplus volumio[3220]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 15:32:54 rivoplus volumio[3220]: info: Successfully built camilladsp PyYAML Dec 30 15:32:54 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:54.841Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:54 rivoplus sudo[8076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 15:32:54 rivoplus sudo[8076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:54 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 30 15:32:54 rivoplus sudo[8076]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:55 rivoplus volumio[3220]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Dec 30 15:32:55 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:55.136Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:55 rivoplus volumio[3220]: info: RAAT: Requesting Headphone Status Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 30 15:32:55 rivoplus volumio[3220]: info: Executing endpoint tc_getconfig Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 30 15:32:55 rivoplus vtcs[8078]: STARTING TidalConnect services, version: 1.5.2.56 Dec 30 15:32:55 rivoplus vtcs[8078]: STARTED TidalConnect services. Dec 30 15:32:55 rivoplus volumio[3220]: info: Executing endpoint tc_connect Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 30 15:32:55 rivoplus volumio[3220]: info: Connecting to TidalConnect Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::servicePushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 15:32:55 rivoplus volumio[3220]: verbose: CURRENT POSITION 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::syncState stateService stop Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: info: No code Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::servicePushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 30 15:32:55 rivoplus volumio[3220]: verbose: CURRENT POSITION 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::syncState stateService stop Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: info: No code Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreStateMachine::pushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioPushState Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:55 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output update for this device Dec 30 15:32:55 rivoplus volumio[3220]: info: MRS: Pushing multiroomSync output Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 30 15:32:55 rivoplus volumio[3220]: info: Updating RAAT Signal Path Dec 30 15:32:55 rivoplus volumio[3220]: info: Updating RAAT Signal Path Dec 30 15:32:55 rivoplus volumio[3220]: info: Updating RAAT Signal Path Dec 30 15:32:55 rivoplus volumio[3220]: info: Updating RAAT Signal Path Dec 30 15:32:55 rivoplus volumio[3220]: info: MCU Signalled Headphone Mode Disabled Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 15:32:55 rivoplus volumio[3220]: info: Reporting Headphone State: false Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:32:55 rivoplus volumio[3220]: info: Updating RAAT Signal Path Dec 30 15:32:55 rivoplus volumio[3220]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Dec 30 15:32:55 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:32:55.708Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:32:55 rivoplus volumio[3220]: info: Disabling MyMusic plugin airplay_emulation Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:32:55 rivoplus volumio[3220]: info: Disabling plugin airplay_emulation Dec 30 15:32:55 rivoplus volumio[3220]: info: Done. Dec 30 15:32:55 rivoplus sudo[8098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 30 15:32:55 rivoplus sudo[8098]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:55 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 30 15:32:55 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 30 15:32:55 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:55 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 30 15:32:55 rivoplus sudo[8098]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:55 rivoplus volumio[3220]: info: Shairport-Sync Stopped Dec 30 15:32:55 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:32:56 rivoplus volumio[3220]: info: Disabling MyMusic plugin cd_controller Dec 30 15:32:56 rivoplus volumio[3220]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Dec 30 15:32:56 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:32:56 rivoplus volumio[3220]: info: Stopping UDEV Watcher for CD Dec 30 15:32:56 rivoplus volumio[3220]: info: Disabling plugin cd_controller Dec 30 15:32:56 rivoplus volumio[3220]: info: Done. Dec 30 15:32:57 rivoplus sudo[8102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 15:32:57 rivoplus sudo[8102]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:57 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 15:32:57 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 15:32:57 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 15:32:57 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 15:32:57 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:57 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 30 15:32:57 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 15:32:57 rivoplus sudo[8102]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:57 rivoplus mpd_monitor.sh[8104]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 15:32:57 rivoplus volumio[3220]: info: Successfully started MPD Monitor Dec 30 15:32:57 rivoplus volumio[3220]: info: TidalConnect service started! Dec 30 15:32:58 rivoplus volumio[3220]: info: Disabling MyMusic plugin qobuzconnect Dec 30 15:32:58 rivoplus qobuz-connect[8006]: 20251230 15:32:58.361 [8006.8006] INFO MediaEngine: [0xab831dd8]: Stopping playback, keeping tracks Dec 30 15:32:58 rivoplus volumio[3220]: info: QobuzConnect: setDeactiveState invoked Dec 30 15:32:58 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:58 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:58 rivoplus sudo[8109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect Dec 30 15:32:58 rivoplus sudo[8109]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:58 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 30 15:32:58 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:58 rivoplus qobuz-connect[8006]: 20251230 15:32:58.440 [8006.8006] INFO SampleApp: Stopping Local configuration server Dec 30 15:32:58 rivoplus qobuz-connect[8006]: 20251230 15:32:58.451 [8006.8006] INFO SampleApp: shat down connection on UNIX socket Dec 30 15:32:58 rivoplus volumio[3220]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 15:32:58 rivoplus volumio[3220]: info: QobuzConnect: setDeactiveState invoked Dec 30 15:32:58 rivoplus volumio[3220]: info: CoreCommandRouter::volumioGetState Dec 30 15:32:58 rivoplus volumio[3220]: info: CorePlayQueue::getTrack 0 Dec 30 15:32:58 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 30 15:32:58 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 30 15:32:58 rivoplus sudo[8109]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:58 rivoplus volumio[3220]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket disconnected and tore down Dec 30 15:32:58 rivoplus volumio[3220]: info: Disabling plugin qobuzconnect Dec 30 15:32:58 rivoplus volumio[3220]: info: Done. Dec 30 15:32:59 rivoplus volumio[3220]: info: Disabling MyMusic plugin raat Dec 30 15:32:59 rivoplus volumio[3220]: info: Stopping RAAT Plugin Dec 30 15:32:59 rivoplus sudo[8113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Dec 30 15:32:59 rivoplus sudo[8113]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:32:59 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 30 15:32:59 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 15:32:59 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 30 15:32:59 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:32:59 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 30 15:32:59 rivoplus sudo[8113]: pam_unix(sudo:session): session closed for user root Dec 30 15:32:59 rivoplus volumio[3220]: info: Raat Daemon stopped successfully Dec 30 15:32:59 rivoplus volumio[3220]: info: Disabling plugin raat Dec 30 15:32:59 rivoplus volumio[3220]: info: Done. Dec 30 15:32:59 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:02 rivoplus volumio[3220]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Dec 30 15:33:02 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:02.098Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:02 rivoplus volumio[3220]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-onbp5t_m Dec 30 15:33:02 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:02.124Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:03 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:04 rivoplus volumio[3220]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Dec 30 15:33:04 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:04.602Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:04 rivoplus volumio[3220]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Dec 30 15:33:04 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:04.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:05 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Dec 30 15:33:05 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:05.543Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:05 rivoplus volumio[3220]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:05 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:05.955Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:06 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Dec 30 15:33:06 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:06.043Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:06 rivoplus volumio[3220]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:06 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:06.212Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:06 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Dec 30 15:33:06 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:06.510Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:06 rivoplus volumio[3220]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:06 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:06.693Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:07 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Dec 30 15:33:07 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:07.481Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:07 rivoplus volumio[3220]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:07 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:07.817Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:07 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:08 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Dec 30 15:33:08 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:08.394Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:08 rivoplus volumio[3220]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:08 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:08.679Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:09 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Dec 30 15:33:09 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:09.184Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:09 rivoplus volumio[3220]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Dec 30 15:33:09 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:09.441Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:09 rivoplus volumio[3220]: info: Collecting zipp>=0.5 (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) Dec 30 15:33:09 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:09.553Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:09 rivoplus volumio[3220]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Dec 30 15:33:09 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:09.916Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:10 rivoplus volumio[3220]: info: Building wheels for collected packages: camilladsp-plot Dec 30 15:33:10 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:10.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:10 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for camilladsp-plot: started Dec 30 15:33:10 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:10.164Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:11 rivoplus volumio[3220]: info: Disabling MyMusic plugin smart_inputs Dec 30 15:33:11 rivoplus volumio[3220]: info: Removeing all smart Inputs Dec 30 15:33:11 rivoplus volumio[3220]: info: Disabling plugin smart_inputs Dec 30 15:33:11 rivoplus volumio[3220]: info: Done. Dec 30 15:33:11 rivoplus volumio[3220]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Dec 30 15:33:11 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:11.752Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:11 rivoplus volumio[3220]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-2d1rh48w/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Dec 30 15:33:11 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:11.780Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:11 rivoplus volumio[3220]: info: Successfully built camilladsp-plot Dec 30 15:33:11 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:11.821Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:11 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:12 rivoplus volumio[3220]: info: Disabling MyMusic plugin tidalconnect Dec 30 15:33:12 rivoplus vtcs[8078]: [2025-12-30 15:33:12.600] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 15:33:12 rivoplus vtcs[8078]: [2025-12-30 15:33:12.602] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 15:33:12 rivoplus volumio[3220]: info: Disabling plugin tidalconnect Dec 30 15:33:12 rivoplus volumio[3220]: info: Done. Dec 30 15:33:12 rivoplus volumio[3220]: info: Installing collected packages: pkgutil-resolve-name, typing-extensions, zipp, importlib-metadata, pyrsistent, importlib-resources, jsonschema, camilladsp-plot Dec 30 15:33:12 rivoplus sudo[8156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 15:33:12 rivoplus sudo[8156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:12 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:12.672Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:12 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 30 15:33:12 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 30 15:33:12 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 30 15:33:12 rivoplus volumio[3220]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 30 15:33:12 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 30 15:33:12 rivoplus sudo[8156]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:13 rivoplus volumio[3220]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Dec 30 15:33:13 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:13.824Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:14 rivoplus volumio[3220]: info: Disabling MyMusic plugin upnp_browser Dec 30 15:33:14 rivoplus volumio[3220]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesDLNAサーバー Dec 30 15:33:14 rivoplus volumio[3220]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:14 rivoplus volumio[3220]: info: Disabling plugin upnp_browser Dec 30 15:33:14 rivoplus volumio[3220]: info: Done. Dec 30 15:33:14 rivoplus nmbd[3088]: [2025/12/30 15:33:14.124834, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 30 15:33:14 rivoplus nmbd[3088]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 30 15:33:14 rivoplus volumio[3220]: info: copying hw detection script Dec 30 15:33:14 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:14.140Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:14 rivoplus volumio[3220]: info: Detected cpu architecture as aarch64 Dec 30 15:33:14 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:14.169Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 30 15:33:15 rivoplus volumio-remote-updater[2861]: [2025-12-30 06:33:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 30 15:33:15 rivoplus volumio-remote-updater[2861]: [2025-12-30 06:33:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 30 15:33:15 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:15.167Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Dec 30 15:33:15 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:15.170Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Dec 30 15:33:15 rivoplus go-librespot[5725]: time="2025-12-30T15:33:15+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 30 15:33:15 rivoplus systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Dec 30 15:33:15 rivoplus sudo[4192]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:15 rivoplus sudo[8046]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:15 rivoplus sudo[5915]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:15 rivoplus systemd[1]: upmpdcli.service: Succeeded. Dec 30 15:33:15 rivoplus systemd[1]: Stopped UPnP Renderer front-end to MPD. Dec 30 15:33:15 rivoplus systemd[1]: volumio.service: Failed with result 'signal'. Dec 30 15:33:15 rivoplus systemd[1]: Started dynamicswap service. Dec 30 15:33:15 rivoplus systemd[1]: dynamicswap.service: Succeeded. Dec 30 15:33:15 rivoplus systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 30 15:33:15 rivoplus systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 30 15:33:15 rivoplus systemd[1]: Started dynamicswap service. Dec 30 15:33:15 rivoplus systemd[1]: Stopped Volumio Backend Module. Dec 30 15:33:15 rivoplus systemd[1]: Started Volumio Backend Module. Dec 30 15:33:15 rivoplus systemd[1]: dynamicswap.service: Succeeded. Dec 30 15:33:16 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:16.173Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Dec 30 15:33:17 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:17.176Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Dec 30 15:33:18 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:18 rivoplus volumio[8198]: info: ----- Volumio3 ---- Dec 30 15:33:18 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:18 rivoplus volumio[8198]: info: ----- System startup ---- Dec 30 15:33:18 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:19 rivoplus volumio[8198]: info: MYVOLUMIO Environment detected Dec 30 15:33:20 rivoplus volumio[8198]: info: Plugin folders cleanup Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning into folder /volumio/app/plugins/ Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category audio_interface Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category miscellanea Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category music_service Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category plugins.json Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category system_controller Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category user_interface Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning into folder /data/plugins/ Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category audio_interface Dec 30 15:33:20 rivoplus volumio[8198]: info: Scanning category music_service Dec 30 15:33:20 rivoplus volumio[8198]: info: Plugin folders cleanup completed Dec 30 15:33:20 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:20 rivoplus volumio[8198]: info: ----- Core plugins startup ---- Dec 30 15:33:20 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:20 rivoplus volumio[8198]: info: Loading plugins from folder /volumio/app/plugins/ Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin bluetooth to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin multiroom to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin upnp to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin metavolumio to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin cd_controller to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin raat to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 30 15:33:20 rivoplus volumio[8198]: info: Loading plugins from folder /data/plugins/ Dec 30 15:33:20 rivoplus volumio[8198]: info: Loading plugin "system"... Dec 30 15:33:20 rivoplus volumio-remote-updater[2861]: [2025-12-30 06:33:20] [connect] Successful connection Dec 30 15:33:20 rivoplus volumio[8198]: info: Loading plugin "appearance"... Dec 30 15:33:21 rivoplus volumio[8198]: info: Loading plugin "network"... Dec 30 15:33:21 rivoplus volumio[8198]: info: Refreshing Cached IP Addresses Dec 30 15:33:21 rivoplus sudo[8228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:33:21 rivoplus sudo[8228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:21 rivoplus sudo[8228]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:21 rivoplus volumio[8198]: info: Loading plugin "services"... Dec 30 15:33:21 rivoplus sudo[8230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:33:21 rivoplus sudo[8230]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:21 rivoplus volumio[8198]: info: Loading plugin "volumio5onboarding"... Dec 30 15:33:21 rivoplus sudo[8230]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:21 rivoplus volumio[8198]: info: Loading plugin "alsa_controller"... Dec 30 15:33:22 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "wizard"... Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "networkfs"... Dec 30 15:33:22 rivoplus volumio[8198]: info: Starting Udev Watcher for removable devices Dec 30 15:33:22 rivoplus volumio[8198]: info: Ignoring mount for partition: BOOT Dec 30 15:33:22 rivoplus volumio[8198]: info: Ignoring mount for partition: volumio Dec 30 15:33:22 rivoplus volumio[8198]: info: Ignoring mount for partition: volumio_data Dec 30 15:33:22 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "volumio_command_line_client"... Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "upnp"... Dec 30 15:33:22 rivoplus volumio[8198]: info: [1767076402291] Starting Upmpd Daemon Dec 30 15:33:22 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "my_music"... Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "mpd"... Dec 30 15:33:22 rivoplus volumio[8198]: info: Plugin upnp_browser is not enabled Dec 30 15:33:22 rivoplus volumio[8198]: info: Plugin bluetooth is not enabled Dec 30 15:33:22 rivoplus volumio[8198]: info: Loading plugin "alarm-clock"... Dec 30 15:33:23 rivoplus volumio[8198]: info: Loading plugin "manifestui"... Dec 30 15:33:23 rivoplus volumio[8198]: info: Plugin metavolumio is not enabled Dec 30 15:33:23 rivoplus volumio[8198]: info: Plugin airplay_emulation is not enabled Dec 30 15:33:23 rivoplus volumio[8198]: info: Plugin cd_controller is not enabled Dec 30 15:33:23 rivoplus volumio[8198]: info: Loading plugin "last_100"... Dec 30 15:33:23 rivoplus volumio[8198]: info: Plugin qobuzconnect is not enabled Dec 30 15:33:23 rivoplus volumio[8198]: info: Plugin raat is not enabled Dec 30 15:33:23 rivoplus volumio[8198]: info: Loading plugin "streaming_services"... Dec 30 15:33:25 rivoplus volumio[8198]: info: Starting Streaming Service Transparent Proxy Dec 30 15:33:25 rivoplus volumio[8198]: info: Plugin tidalconnect is not enabled Dec 30 15:33:25 rivoplus volumio[8198]: info: Loading plugin "webradio"... Dec 30 15:33:25 rivoplus volumio[8198]: info: Loading plugin "i2s_dacs"... Dec 30 15:33:25 rivoplus volumio[8198]: info: I2S DAC not set, start Auto-detection Dec 30 15:33:25 rivoplus volumio[8198]: info: Loading plugin "volumiodiscovery"... Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** For more information see Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 15:33:25 rivoplus volumio[8198]: *** WARNING *** For more information see Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** For more information see Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 15:33:25 rivoplus node[8198]: *** WARNING *** For more information see Dec 30 15:33:25 rivoplus volumio[8198]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 30 15:33:25 rivoplus volumio[8198]: info: Discovery: Started advertising with name: Rivoplus Dec 30 15:33:25 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 15:33:25 rivoplus volumio[8198]: info: Loading plugin "spop"... Dec 30 15:33:26 rivoplus volumio[8198]: STREAMING PROXY: Starting server on port 3245 Dec 30 15:33:26 rivoplus volumio[8198]: Node JS runtime: 14 Dec 30 15:33:27 rivoplus volumio[8198]: info: Plugin multiroom is not enabled Dec 30 15:33:27 rivoplus volumio[8198]: info: Loading plugin "outputs"... Dec 30 15:33:27 rivoplus volumio[8198]: info: Loading plugin "albumart"... Dec 30 15:33:27 rivoplus volumio[8198]: info: Plugin example_plugin is not enabled Dec 30 15:33:27 rivoplus volumio[8198]: info: Loading plugin "hi_res_audio"... Dec 30 15:33:27 rivoplus volumio[8198]: Forking 3 albumart workers Dec 30 15:33:28 rivoplus volumio5-onboarding[4119]: time=2025-12-30T06:33:28.178Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:37588->127.0.0.1:3000: i/o timeout" Dec 30 15:33:29 rivoplus volumio[8198]: Starting albumart workers Dec 30 15:33:29 rivoplus volumio[8198]: Starting albumart workers Dec 30 15:33:29 rivoplus volumio[8198]: Starting albumart workers Dec 30 15:33:29 rivoplus volumio[8198]: info: Applying required configuration parameters for plugin hi_res_audio Dec 30 15:33:29 rivoplus volumio[8198]: info: Loading plugin "inputs"... Dec 30 15:33:30 rivoplus volumio[8198]: info: Loading plugin "qobuz"... Dec 30 15:33:32 rivoplus volumio-remote-updater[2861]: [2025-12-30 06:33:32] [connect] Successful connection Dec 30 15:33:32 rivoplus volumio[8198]: info: Plugin smart_inputs is not enabled Dec 30 15:33:32 rivoplus volumio[8198]: info: Loading plugin "tidal"... Dec 30 15:33:34 rivoplus volumio[8198]: info: Loading plugin "rivopluscontrol"... Dec 30 15:33:34 rivoplus volumio[8198]: info: Adding this device properties Dec 30 15:33:34 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 30 15:33:34 rivoplus volumio[8198]: info: Setting Additional Device Volumio Properties: [object Object] Dec 30 15:33:34 rivoplus volumio[8198]: info: Loading plugin "updater_comm"... Dec 30 15:33:35 rivoplus volumio[8198]: info: Loading plugin "cec_controller"... Dec 30 15:33:35 rivoplus volumio[8198]: info: Applying required configuration parameters for plugin cec_controller Dec 30 15:33:35 rivoplus volumio[8198]: info: Plugin mpdemulation is not enabled Dec 30 15:33:35 rivoplus volumio[8198]: info: Loading plugin "rest_api"... Dec 30 15:33:35 rivoplus volumio[8198]: info: Loading plugin "websocket"... Dec 30 15:33:35 rivoplus volumio[8198]: info: Starting Socket.io Server version 2.3.0 Dec 30 15:33:35 rivoplus volumio[8198]: info: Plugin fusiondsp is not enabled Dec 30 15:33:35 rivoplus volumio[8198]: info: Loading i18n strings for locale ja Dec 30 15:33:35 rivoplus volumio[8198]: Updating browse sources language Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::initPlayerControls Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 15:33:35 rivoplus volumio[8198]: Express server listening on port 3000 Dec 30 15:33:35 rivoplus volumio[8198]: [Metrics] WebUI: 18s 116.69ms Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreStateMachine::resetVolumioState Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreStateMachine::getcurrentVolume Dec 30 15:33:35 rivoplus volumio[8198]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:36 rivoplus sudo[8403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:33:36 rivoplus sudo[8403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus sudo[8403]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus volumio[8198]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 30 15:33:36 rivoplus volumio[8198]: info: Completed loading Core Plugins Dec 30 15:33:36 rivoplus sudo[8405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:33:36 rivoplus volumio[8198]: info: Preparing to generate the ALSA configuration file Dec 30 15:33:36 rivoplus sudo[8405]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus sudo[8405]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus volumio[8198]: info: Cannot read play queue from file Dec 30 15:33:36 rivoplus volumio[8198]: info: Volumio Network Manager: Network status updated: 1 Dec 30 15:33:36 rivoplus volumio[8198]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 2 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Dec 30 15:33:36 rivoplus volumio-remote-updater[2861]: [2025-12-30 06:33:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767076412 101 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 8 Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 15:33:36 rivoplus volumio[8198]: info: VolumeController:: Volume=41 Mute =false Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:36 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreStateMachine::updateTrackBlock Dec 30 15:33:36 rivoplus volumio[8198]: info: CorePlayQueue::getTrackBlock Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 15:33:36 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 15:33:36 rivoplus volumio[8198]: info: Asound.conf file unchanged, so no further update is needed Dec 30 15:33:36 rivoplus volumio[8198]: info: Output device has changed, restarting MPD Dec 30 15:33:36 rivoplus sudo[8418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 15:33:36 rivoplus sudo[8418]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus volumio[8198]: info: ___________ START PLUGINS ___________ Dec 30 15:33:36 rivoplus sudo[8418]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus sudo[8421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 15:33:36 rivoplus sudo[8421]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus volumio[8198]: info: ControllerMpd::onStart: Initializing MPD Dec 30 15:33:36 rivoplus volumio[8198]: info: Creating MPD Configuration file Dec 30 15:33:36 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 15:33:36 rivoplus sudo[8424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Dec 30 15:33:36 rivoplus sudo[8424]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus sudo[8426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 15:33:36 rivoplus sudo[8426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 15:33:36 rivoplus volumio[8198]: info: [1767076416780] CoreMusicLibrary::Adding element Last_100 Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:36 rivoplus volumio[8198]: info: Streaming services startup Dec 30 15:33:36 rivoplus volumio[8198]: info: Starting Streaming Daemon Dec 30 15:33:36 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 15:33:36 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 15:33:36 rivoplus sudo[8426]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus sudo[8429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 15:33:36 rivoplus sudo[8429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 15:33:36 rivoplus sudo[8424]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 30 15:33:36 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 15:33:36 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 15:33:36 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 15:33:36 rivoplus sudo[8436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 15:33:36 rivoplus sudo[8436]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 15:33:36 rivoplus volumio[8198]: info: [1767076416895] CoreMusicLibrary::Adding element Webradio Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:36 rivoplus sudo[8436]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:36 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:33:36 rivoplus volumio[8198]: info: Initializing BBC Radios Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:37 rivoplus volumio[8198]: info: Creating Spotify config file Dec 30 15:33:37 rivoplus sudo[8438]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:37 rivoplus sudo[8438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus volumio[8198]: error: Hi Res Audio Failed Login: Missing Login Data Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 30 15:33:37 rivoplus sudo[8438]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus volumio[8198]: info: Initializing Serial Communication on port /dev/ttyS3 Dec 30 15:33:37 rivoplus volumio[8198]: info: Touch Event Listener Process Starting Dec 30 15:33:37 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Dec 30 15:33:37 rivoplus sudo[8461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Dec 30 15:33:37 rivoplus sudo[8461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Dec 30 15:33:37 rivoplus sudo[8468]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8468]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus sudo[8472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Dec 30 15:33:37 rivoplus sudo[8472]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8472]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus sudo[8475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Dec 30 15:33:37 rivoplus sudo[8475]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8475]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus sudo[8478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Dec 30 15:33:37 rivoplus sudo[8478]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8478]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus volumio[8198]: info: Apply VIM3L Onboard LEDs Settings Dec 30 15:33:37 rivoplus volumio[8198]: info: Turning On Onboard LEDs Dec 30 15:33:37 rivoplus sudo[8482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 15:33:37 rivoplus sudo[8482]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8482]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus volumio[8198]: info: Starting CEC Event listener Dec 30 15:33:37 rivoplus volumio[8198]: info: Volumio Calling Home Dec 30 15:33:37 rivoplus sudo[8486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 15:33:37 rivoplus sudo[8486]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:37 rivoplus sudo[8486]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:37 rivoplus volumio[8198]: info: Stopping AccessToken refresher cron for QOBUZ Dec 30 15:33:37 rivoplus volumio[8198]: info: AccessToken refresher cron started for QOBUZ Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding TIDAL REST API Endpoints Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding QOBUZ REST API Endpoints Dec 30 15:33:37 rivoplus volumio[8198]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreStateMachine::setRepeat null single undefined Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:37 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreStateMachine::setRandom null Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:37 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:37 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:37 rivoplus volumio[8198]: info: Serial port opened successfully Dec 30 15:33:37 rivoplus volumio[8198]: info: Sending serial start messages Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: Reporting MCU Network Status: 1 Dec 30 15:33:38 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Dec 30 15:33:38 rivoplus volumio[8198]: error: Cannot start Volumio Streaming Daemon Dec 30 15:33:38 rivoplus volumio[8198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 15:33:38 rivoplus volumio[8198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 15:33:38 rivoplus volumio[8198]: info: MPD Permissions set Dec 30 15:33:38 rivoplus volumio[8198]: info: MPD Permissions set Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting Device Volume Override Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 15:33:38 rivoplus volumio[8198]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 15:33:38 rivoplus volumio[8198]: info: Disabling external Volume Control Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:38 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:38 rivoplus volumio[8198]: info: VolumeController:: Volume=41 Mute =false Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:38 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:38 rivoplus sudo[8501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 15:33:38 rivoplus sudo[8501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:38 rivoplus volumio[8198]: info: Spotify config file written Dec 30 15:33:38 rivoplus systemd[1]: Started UPnP Renderer front-end to MPD. Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting Additional System Software info: Hardware Revision: 1.0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting HW Firmware info: undefined Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting HW Version info: 1.0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting HW Firmware info: 0.3.0 Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 30 15:33:38 rivoplus volumio[8198]: info: Setting HW Version info: 1.0 Dec 30 15:33:38 rivoplus volumio[8198]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 30 15:33:38 rivoplus volumio[8198]: info: MCU Signalled Headphone Mode Disabled Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 30 15:33:38 rivoplus volumio[8198]: info: MCU Signalled Sleep Mode Disabled Dec 30 15:33:38 rivoplus sudo[8501]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:38 rivoplus sudo[8504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 15:33:38 rivoplus sudo[8504]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:38 rivoplus systemd[1]: Stopping go-librespot Daemon... Dec 30 15:33:38 rivoplus systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 30 15:33:38 rivoplus systemd[1]: go-librespot-daemon.service: Succeeded. Dec 30 15:33:38 rivoplus systemd[1]: Stopped go-librespot Daemon. Dec 30 15:33:38 rivoplus volumio[8198]: info: Enabling Advanced system settings configuration Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Dec 30 15:33:38 rivoplus volumio[8198]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 15:33:38 rivoplus volumio[8198]: info: MCU Signalled Auto Boot Mode On Power Disabled Dec 30 15:33:38 rivoplus systemd[1]: Started go-librespot Daemon. Dec 30 15:33:38 rivoplus sudo[8504]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:38 rivoplus sudo[8508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 30 15:33:38 rivoplus go-librespot[8509]: go-librespot daemon starting... Dec 30 15:33:38 rivoplus sudo[8508]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:38 rivoplus sudo[8508]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:38 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=info msg="running go-librespot 0.4.0" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=debug msg="app state loaded" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=debug msg="stored credentials not found" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 15:33:38 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 30 15:33:38 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Dec 30 15:33:38 rivoplus volumio[8198]: info: Volumio called home Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:38 rivoplus volumio[8198]: info: No need to fix Spotify hosts Dec 30 15:33:38 rivoplus volumio[8198]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 30 15:33:38 rivoplus volumio[8198]: info: Discovery: adding f2d29d31-03de-4527-b5d5-eb1b977a9ca1 Dec 30 15:33:38 rivoplus volumio[8198]: info: Discovery: Found device Rivoplus Dec 30 15:33:38 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:38 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 30 15:33:38 rivoplus go-librespot[8509]: time="2025-12-30T15:33:38+09:00" level=info msg="zeroconf server listening on port 37189" Dec 30 15:33:38 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 30 15:33:38 rivoplus volumio[8198]: info: Upmpdcli Daemon Started Dec 30 15:33:38 rivoplus volumio[8198]: info: MP1 GPIO: Signalled system ready via GPIO Dec 30 15:33:39 rivoplus sudo[8534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 30 15:33:39 rivoplus sudo[8534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 15:33:39 rivoplus sudo[8534]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:39 rivoplus volumio[8198]: info: VolumeController:: Volume=41 Mute =false Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:39 rivoplus volumio[8198]: info: Adding Inputs via Serial API Dec 30 15:33:39 rivoplus volumio[8198]: info: Adding Advanced Audio Settings via Serial API Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 30 15:33:39 rivoplus volumio[8198]: info: Additional UI Settings Added for plugin music_service/inputs Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 12 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::servicePushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Dec 30 15:33:39 rivoplus volumio[8198]: verbose: CURRENT POSITION 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::syncState stateService stop Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:39 rivoplus volumio[8198]: info: No code Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreStateMachine::pushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioPushState Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 15:33:39 rivoplus volumio[8198]: info: Received Get System Info Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:33:39 rivoplus volumio[8198]: info: Discovery: Getting this device information Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:33:39 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:39 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:39 rivoplus volumio[8198]: info: Listing playlists Dec 30 15:33:39 rivoplus volumio[8198]: info: MCU Signalled Playback Inactive Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus mpd[8462]: Dec 30 15:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 15:33:39 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 15:33:39 rivoplus sudo[8421]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:39 rivoplus sudo[8429]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: info: Completed starting Core Plugins Dec 30 15:33:39 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:39 rivoplus volumio[8198]: info: ----- MyVolumio plugins startup ---- Dec 30 15:33:39 rivoplus volumio[8198]: info: ------------------------------------------- Dec 30 15:33:39 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 30 15:33:39 rivoplus volumio[8198]: error: MPD error: The expression evaluated to a falsy value: Dec 30 15:33:39 rivoplus volumio[8198]: assert.ok(self.idling) Dec 30 15:33:39 rivoplus volumio[8198]: error: The expression evaluated to a falsy value: Dec 30 15:33:39 rivoplus volumio[8198]: assert.ok(self.idling) Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: info: MPD running with PID8462 Dec 30 15:33:39 rivoplus volumio[8198]: ,establishing connection Dec 30 15:33:39 rivoplus volumio[8198]: error: updateQueue error: null Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: error: updateQueue error: null Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8505]: writing RSA key Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:39 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: info: Turning Off Onboard LEDs Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to 192.168.3.4 from 192.168.3.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Dec 30 15:33:40 rivoplus sudo[8566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 30 15:33:40 rivoplus sudo[8566]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:40 rivoplus sudo[8566]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:40 rivoplus sudo[8570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 30 15:33:40 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 15:33:40 rivoplus sudo[8570]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 15:33:40 rivoplus volumio[8198]: info: Received Get System Info Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 15:33:40 rivoplus volumio[8198]: info: Discovery: Getting this device information Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:40 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:40 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:40 rivoplus sudo[8570]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:40 rivoplus volumio[8198]: info: Listing playlists Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreStateMachine::getQueue Dec 30 15:33:40 rivoplus volumio[8198]: info: CorePlayQueue::getQueue Dec 30 15:33:40 rivoplus volumio[8198]: info: Onboard LEDs initialized Dec 30 15:33:40 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 30 15:33:40 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:40 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:41 rivoplus volumio[8198]: info: go-librespot daemon successfully initialized Dec 30 15:33:42 rivoplus sudo[8590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 15:33:42 rivoplus sudo[8590]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:42 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 15:33:42 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 15:33:42 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 15:33:42 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 15:33:42 rivoplus sudo[8593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 15:33:42 rivoplus sudo[8593]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:42 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 15:33:42 rivoplus sudo[8590]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:42 rivoplus mpd_monitor.sh[8594]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 15:33:42 rivoplus volumio[8198]: info: Successfully started MPD Monitor Dec 30 15:33:42 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 30 15:33:42 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 30 15:33:42 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 30 15:33:42 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 30 15:33:42 rivoplus systemd[1]: Started MPD Monitor Service. Dec 30 15:33:42 rivoplus sudo[8593]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:42 rivoplus volumio[8198]: info: Successfully started MPD Monitor Dec 30 15:33:42 rivoplus mpd_monitor.sh[8597]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 15:33:44 rivoplus volumio[8198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:33:44 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 15:33:44 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 15:33:44 rivoplus sudo[8606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 15:33:44 rivoplus sudo[8606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus sudo[8606]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus sudo[8611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:33:44 rivoplus sudo[8611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus sudo[8611]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: Error getting the SSID Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus sudo[8620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:33:44 rivoplus sudo[8620]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus sudo[8620]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: Error getting the SSID Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus sudo[8627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 15:33:44 rivoplus sudo[8627]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus sudo[8627]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: Error getting the SSID Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 30 15:33:44 rivoplus sudo[8633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 15:33:44 rivoplus sudo[8633]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 15:33:44 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 15:33:44 rivoplus sudo[8633]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus sudo[8638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 15:33:44 rivoplus sudo[8638]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:44 rivoplus sudo[8638]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:44 rivoplus volumio[8198]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 30 15:33:44 rivoplus volumio[8198]: info: Initializing connection to go-librespot Websocket Dec 30 15:33:44 rivoplus go-librespot[8509]: time="2025-12-30T15:33:44+09:00" level=debug msg="new websocket client" Dec 30 15:33:45 rivoplus volumio[8198]: info: Connection to go-librespot Websocket established Dec 30 15:33:47 rivoplus volumio[8198]: info: Checking for updated MCU Firmware Dec 30 15:33:47 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 15:33:47 rivoplus volumio[8198]: info: Firware on device is on latest version, no need to update Dec 30 15:33:48 rivoplus volumio[8198]: info: Getting Spotify volume Dec 30 15:33:48 rivoplus volumio[8198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 30 15:33:48 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:48 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:48 rivoplus volumio[8198]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 30 15:33:48 rivoplus volumio[8198]: SPOTIFY: SPOTIFY VOLUME undefined Dec 30 15:33:48 rivoplus volumio[8198]: SPOTIFY: VOLUMIO VOLUME 100 Dec 30 15:33:48 rivoplus volumio[8198]: info: Aligning Spotify Volume to Volumio Volume Dec 30 15:33:48 rivoplus volumio[8198]: info: CoreCommandRouter::volumioGetState Dec 30 15:33:48 rivoplus volumio[8198]: info: CorePlayQueue::getTrack 0 Dec 30 15:33:48 rivoplus volumio[8198]: info: Setting Spotify Volume from Volumio: 100 Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 30 15:33:48 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 30 15:33:49 rivoplus volumio[8198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 30 15:33:49 rivoplus volumio[8198]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 30 15:33:49 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:49 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:49 rivoplus volumio[8198]: info: Starting MyVolumio Remote Streaming Endpoints Dec 30 15:33:49 rivoplus volumio[8198]: info: MyVolumio login type: Token Dec 30 15:33:49 rivoplus volumio[8198]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 30 15:33:49 rivoplus volumio[8198]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Dec 30 15:33:49 rivoplus volumio[8198]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 30 15:33:49 rivoplus volumio[8198]: info: Sending Spotify command with payload to local API: /player/volume Dec 30 15:33:50 rivoplus volumio[8198]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 30 15:33:50 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 15:33:50 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 30 15:33:50 rivoplus volumio[8198]: info: MyVolumio token set successfully Dec 30 15:33:50 rivoplus volumio[8198]: info: MYVOLUMIO: Adding device Dec 30 15:33:50 rivoplus volumio[8198]: info: MYVOLUMIO: Evaluating Server Dec 30 15:33:51 rivoplus volumio[8198]: info: MyVolumio status changed Dec 30 15:33:51 rivoplus volumio[8198]: info: Streaming services startup Dec 30 15:33:51 rivoplus volumio[8198]: info: Starting Streaming Daemon Dec 30 15:33:51 rivoplus volumio[8198]: info: Removing browser output: myVolumio user plan is not superstar Dec 30 15:33:51 rivoplus volumio[8198]: info: Removing audio output: Dec 30 15:33:51 rivoplus volumio[8198]: info: Stoppping Tunnel 1 Dec 30 15:33:51 rivoplus sudo[8666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 15:33:51 rivoplus sudo[8666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:51 rivoplus sudo[8666]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:51 rivoplus sudo[8669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 30 15:33:51 rivoplus sudo[8669]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:51 rivoplus volumio[8198]: error: Cannot start Volumio Streaming Daemon Dec 30 15:33:51 rivoplus volumio[8198]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 15:33:51 rivoplus volumio[8198]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 15:33:51 rivoplus sudo[8669]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:51 rivoplus volumio[8198]: info: Remote SSH Stopped Dec 30 15:33:51 rivoplus volumio[8198]: info: Setting Geolocation for MyVolumio to as1 Dec 30 15:33:51 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:51 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:52 rivoplus volumio[8198]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 30 15:33:52 rivoplus volumio[8198]: info: Updating MyVolumio device info Dec 30 15:33:52 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:52 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:53 rivoplus volumio[8198]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 30 15:33:54 rivoplus volumio[8198]: info: Enabling plugin fusiondsp Dec 30 15:33:54 rivoplus volumio[8198]: info: Loading plugin "fusiondsp"... Dec 30 15:33:54 rivoplus volumio[8198]: info: Preparing to generate the ALSA configuration file Dec 30 15:33:54 rivoplus volumio[8198]: info: Asound.conf file unchanged, so no further update is needed Dec 30 15:33:54 rivoplus volumio[8198]: info: Output device has changed, restarting MPD Dec 30 15:33:54 rivoplus sudo[8690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 15:33:54 rivoplus sudo[8690]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: PLUGIN START: fusiondsp Dec 30 15:33:54 rivoplus sudo[8690]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:54 rivoplus volumio[8198]: info: Loading i18n strings for locale ja Dec 30 15:33:54 rivoplus sudo[8692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 15:33:54 rivoplus sudo[8692]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: FusionDsp - mixtype--------------------- Hardware Dec 30 15:33:54 rivoplus volumio[8198]: info: Preparing to generate the ALSA configuration file Dec 30 15:33:54 rivoplus volumio[8198]: info: Done. Dec 30 15:33:54 rivoplus volumio[8198]: info: MPD Permissions set Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 15:33:54 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 15:33:54 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 15:33:54 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:54 rivoplus volumio[8198]: info: Asound.conf file unchanged, so no further update is needed Dec 30 15:33:54 rivoplus volumio[8198]: info: Output device has changed, restarting MPD Dec 30 15:33:54 rivoplus sudo[8710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 15:33:54 rivoplus sudo[8710]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:54 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus sudo[8710]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:33:55 rivoplus volumio[8198]: info: MPD Permissions set Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus sudo[8713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 15:33:55 rivoplus sudo[8713]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 30 15:33:55 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 30 15:33:55 rivoplus systemd[1]: mpd.service: Succeeded. Dec 30 15:33:55 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 30 15:33:55 rivoplus systemd[1]: Starting Music Player Daemon... Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:55 rivoplus sudo[8721]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 15:33:55 rivoplus sudo[8721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:55 rivoplus sudo[8721]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:55 rivoplus volumio[8198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 15:33:56 rivoplus volumio[8198]: info: MYVOLUMIO: Adding device Dec 30 15:33:56 rivoplus volumio[8198]: info: MYVOLUMIO: Evaluating Server Dec 30 15:33:57 rivoplus volumio[8198]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 30 15:33:57 rivoplus volumio[8198]: info: camilladsp service started and running in background, instance 1 Dec 30 15:33:57 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 15:33:57 rivoplus volumio[8198]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 15:33:57 rivoplus volumio[8198]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 30 15:33:57 rivoplus volumio[8198]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 30 15:33:57 rivoplus volumio[8198]: info: FusionDsp loaded Dec 30 15:33:57 rivoplus volumio[8198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 15:33:57 rivoplus sudo[8758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 30 15:33:57 rivoplus sudo[8758]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 15:33:57 rivoplus volumio[8198]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 30 15:33:57 rivoplus volumio[8198]: info: Adding Signal Path Element [object Object] Dec 30 15:33:57 rivoplus volumio[8198]: info: Adding fusiondspeq DSP Signal Path Element Dec 30 15:33:57 rivoplus volumio[8198]: info: FusionDsp - ---- installed callbackRead Dec 30 15:33:57 rivoplus volumio[8198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 15:33:57 rivoplus volumio[8198]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 30 15:33:57 rivoplus volumio[8198]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 30 15:33:57 rivoplus volumio[8198]: at onErrorNT (internal/child_process.js:472:16) Dec 30 15:33:57 rivoplus volumio[8198]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 30 15:33:57 rivoplus volumio[8198]: at runNextTicks (internal/process/task_queues.js:64:3) Dec 30 15:33:57 rivoplus volumio[8198]: at listOnTimeout (internal/timers.js:526:9) Dec 30 15:33:57 rivoplus volumio[8198]: at processTimers (internal/timers.js:500:7) { Dec 30 15:33:57 rivoplus volumio[8198]: errno: -2, Dec 30 15:33:57 rivoplus volumio[8198]: code: 'ENOENT', Dec 30 15:33:57 rivoplus volumio[8198]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 15:33:57 rivoplus volumio[8198]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 30 15:33:57 rivoplus volumio[8198]: spawnargs: [ Dec 30 15:33:57 rivoplus volumio[8198]: '-p', Dec 30 15:33:57 rivoplus volumio[8198]: 9876, Dec 30 15:33:57 rivoplus volumio[8198]: '-o', Dec 30 15:33:57 rivoplus volumio[8198]: '/tmp/camilladsp.log', Dec 30 15:33:57 rivoplus volumio[8198]: '-l', Dec 30 15:33:57 rivoplus volumio[8198]: 'warn', Dec 30 15:33:57 rivoplus volumio[8198]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 30 15:33:57 rivoplus volumio[8198]: ] Dec 30 15:33:57 rivoplus volumio[8198]: } Dec 30 15:33:57 rivoplus volumio[8198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 15:33:57 rivoplus sudo[8758]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:57 rivoplus systemd[1]: Started FusionDsp Daemon. Dec 30 15:33:57 rivoplus mpd[8724]: Dec 30 15:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 15:33:57 rivoplus systemd[1]: Started Music Player Daemon. Dec 30 15:33:57 rivoplus sudo[8713]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:57 rivoplus sudo[8692]: pam_unix(sudo:session): session closed for user root Dec 30 15:33:57 rivoplus sudo[8772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 15:32 Dec 30 15:33:57 rivoplus sudo[8772]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"