-- Logs begin at Sat 2025-12-27 05:49:09 CST, end at Sun 2025-12-28 11:51:28 CST. -- Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.157 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Received SetState message: Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.157 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Playing state: Paused Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Playback position: Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Queue version: Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Current track: Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Next track: Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO MediaEngine: [0xab7c33c8]: Pausing playback Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO AudioRenderer: [0xab7c35e8]: Pausing audio renderer Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO SampleApp: Pausing playback Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO SampleApp: Playback state changed: Paused Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO StreamingEventReporter: [0xab7c3f20]: Reporting streaming ended event Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.158 [4359.4359] INFO HttpDownloader: [0xab7c3f78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.159 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Need to send state update Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.159 [4359.4359] INFO QConnectMessageSender: [0xab8faa68]: Sending StateUpdated message Dec 28 11:50:00 rivoplus qobuz-connect[4359]: 20251228 11:50:00.159 [4359.4359] INFO QwspMessageSender: [0xab8b89d0]: Sending Payload message Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:00 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:00 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:00 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:00 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:00 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:00 rivoplus volumio[3102]: info: MCU Signalled Playback Inactive Dec 28 11:50:12 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48:3000 from 192.168.1.220 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48:3000 from 192.168.1.220 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 28 11:50:12 rivoplus sudo[5260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 28 11:50:12 rivoplus sudo[5260]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus sudo[5260]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus sudo[5266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 11:50:12 rivoplus sudo[5266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: Error getting the SSID Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus sudo[5273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 11:50:12 rivoplus sudo[5266]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus sudo[5273]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus sudo[5279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 11:50:12 rivoplus sudo[5279]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus sudo[5273]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: Error getting the SSID Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus sudo[5279]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: Error getting the SSID Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus kernel: dhd_ioctl_entry: Interface is down Dec 28 11:50:12 rivoplus sudo[5283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 11:50:12 rivoplus sudo[5283]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 11:50:12 rivoplus sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:12 rivoplus sudo[5283]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus sudo[5288]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48:3000 from 192.168.1.220 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:12 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 28 11:50:12 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48:3000 from 192.168.1.220 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 28 11:50:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 28 11:50:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 28 11:50:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 28 11:50:17 rivoplus sudo[5311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 11:50:17 rivoplus sudo[5311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:17 rivoplus sudo[5311]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:17 rivoplus sudo[5315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 11:50:17 rivoplus sudo[5315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:17 rivoplus sudo[5315]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:17 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48 from 192.168.1.220 UA: Mozilla/5.0 (Linux; Android 13; SM-A515F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 28 11:50:18 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 11:50:18 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 28 11:50:18 rivoplus sudo[5319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 11:50:18 rivoplus sudo[5319]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:18 rivoplus sudo[5319]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:18 rivoplus sudo[5321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 11:50:18 rivoplus sudo[5321]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:18 rivoplus sudo[5321]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:18 rivoplus volumio[3102]: verbose: New Socket.io Connection to 192.168.1.48 from 192.168.1.220 UA: Mozilla/5.0 (Linux; Android 13; SM-A515F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 28 11:50:18 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 11:50:19 rivoplus volumio[3102]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 28 11:50:19 rivoplus volumio[3102]: info: Received Get System Info Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:19 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:19 rivoplus volumio[3102]: info: Listing playlists Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 11:50:19 rivoplus volumio[3102]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 11:50:20 rivoplus volumio[3102]: info: Received Get System Info Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:20 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:20 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioVolatilePlay Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreStateMachine::volatilePlay Dec 28 11:50:21 rivoplus volumio[3102]: info: QobuzConnect: Received play Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.355 [4359.4359] INFO MediaEngine: [0xab7c33c8]: Resuming playback Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.355 [4359.4359] INFO AudioRenderer: [0xab7c35e8]: Resuming audio renderer Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.355 [4359.4359] INFO SampleApp: Resuming playback Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.356 [4359.4359] INFO SampleApp: Playback state changed: Playing Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.356 [4359.4359] INFO StreamingEventReporter: [0xab7c3f20]: Reporting streaming started event Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.356 [4359.4359] INFO HttpDownloader: [0xab7c3f78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.356 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Need to send state update Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.357 [4359.4359] INFO QConnectMessageSender: [0xab8faa68]: Sending StateUpdated message Dec 28 11:50:21 rivoplus qobuz-connect[4359]: 20251228 11:50:21.357 [4359.4359] INFO QwspMessageSender: [0xab8b89d0]: Sending Payload message Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:21 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:21 rivoplus volumio[3102]: info: Signalling Playback active due to playback status change Dec 28 11:50:21 rivoplus volumio[3102]: info: Signalling Playback active due to playback status change Dec 28 11:50:21 rivoplus volumio[3102]: info: Signalling Playback active due to playback status change Dec 28 11:50:21 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:21 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:21 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:21 rivoplus volumio[3102]: info: MCU Signalled Playback Active Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 11:50:21 rivoplus volumio[3102]: info: Received Get System Info Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:21 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:21 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPause Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::pause Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::servicePause Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::servicePause Dec 28 11:50:24 rivoplus volumio[3102]: info: QobuzConnect: Received pause Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.080 [4359.4359] INFO MediaEngine: [0xab7c33c8]: Pausing playback Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO AudioRenderer: [0xab7c35e8]: Pausing audio renderer Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO SampleApp: Pausing playback Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO SampleApp: Playback state changed: Paused Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO StreamingEventReporter: [0xab7c3f20]: Reporting streaming ended event Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO HttpDownloader: [0xab7c3f78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Need to send state update Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.081 [4359.4359] INFO QConnectMessageSender: [0xab8faa68]: Sending StateUpdated message Dec 28 11:50:24 rivoplus qobuz-connect[4359]: 20251228 11:50:24.082 [4359.4359] INFO QwspMessageSender: [0xab8b89d0]: Sending Payload message Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:50:24 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:50:24 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:50:24 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:24 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:50:24 rivoplus volumio[3102]: info: MCU Signalled Playback Inactive Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 11:50:28 rivoplus volumio[3102]: No protocol specified Dec 28 11:50:28 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 11:50:28 rivoplus volumio[3102]: No protocol specified Dec 28 11:50:28 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 11:50:37 rivoplus volumio[3102]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 28 11:50:37 rivoplus volumio[3102]: info: Received Get System Version Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 11:50:37 rivoplus volumio[3102]: info: Received Get System Info Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 11:50:37 rivoplus volumio[3102]: info: Discovery: Getting this device information Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:50:37 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 11:50:43 rivoplus nmbd[3068]: [2025/12/28 11:50:43.962308, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 28 11:50:43 rivoplus nmbd[3068]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.48 for name WORKGROUP<1d>. Dec 28 11:50:43 rivoplus nmbd[3068]: This response was from IP 192.168.1.229, reporting an IP address of 192.168.1.229. Dec 28 11:50:53 rivoplus volumio[3102]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 28 11:50:53 rivoplus sudo[5407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 11:50:53 rivoplus sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus sudo[5407]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:53 rivoplus sudo[5410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 11:50:53 rivoplus sudo[5410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:53 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 28 11:50:53 rivoplus systemd[1]: mpd.service: Succeeded. Dec 28 11:50:53 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:50:53 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 28 11:50:53 rivoplus systemd[1]: Starting Music Player Daemon... Dec 28 11:50:53 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:50:53 rivoplus volumio[3102]: info: MPD Permissions set Dec 28 11:50:53 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:50:53 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:50:53 rivoplus sudo[5419]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 11:50:53 rivoplus sudo[5419]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:53 rivoplus sudo[5419]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:55 rivoplus mpd[5429]: Dec 28 11:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 11:50:55 rivoplus systemd[1]: Started Music Player Daemon. Dec 28 11:50:55 rivoplus sudo[5410]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:55 rivoplus volumio[3102]: error: updateQueue error: null Dec 28 11:50:58 rivoplus sudo[5433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 11:50:58 rivoplus sudo[5433]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:50:58 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 28 11:50:58 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 28 11:50:58 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 28 11:50:58 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 28 11:50:58 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:50:58 rivoplus systemd[1]: Started MPD Monitor Service. Dec 28 11:50:58 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:50:58 rivoplus sudo[5433]: pam_unix(sudo:session): session closed for user root Dec 28 11:50:58 rivoplus volumio[3102]: info: Successfully started MPD Monitor Dec 28 11:50:58 rivoplus mpd_monitor.sh[5435]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 11:51:10 rivoplus volumio[3102]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: Restoring Previous Volume level: 100 false false Dec 28 11:51:10 rivoplus volumio[3102]: info: Volume configurations have been set Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: setDeactiveState invoked Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Dec 28 11:51:10 rivoplus volumio[3102]: verbose: UNSET VOLATILE: Service: qobuzconnect Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: Received stop Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.257 [4359.4359] INFO MediaEngine: [0xab7c33c8]: Stopping playback, keeping tracks Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.257 [4359.4359] INFO AudioRenderer: [0xab7c35e8]: Stopping audio renderer Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.257 [4359.4359] INFO SampleApp: Stopping playback Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.259 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Clearing all streams Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.259 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 1]: Disposing of audio stream Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.259 [4359.4359] INFO ContentFetcher: [0xab8b1198]: Canceling download Dec 28 11:51:10 rivoplus vtcs[4375]: [2025-12-28 11:51:10.259] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 28 11:51:10 rivoplus vtcs[4375]: [2025-12-28 11:51:10.259] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 28 11:51:10 rivoplus volumio[3102]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.267 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: New stream: 3 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO HttpDownloader: [0xab7cf580]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9f5f8d51ac163b850021b5be4c22aa30&request_ts=1766940670&track_id=364033072 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO HttpDownloader: [0xab7c7d78]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=364033072 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO HttpDownloader: [0xab7d8320]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a9e042256193b6022471e330983e0595&request_ts=1766940670&track_id=364033072 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 3]: Running audio stream Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: New stream: 4 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO HttpDownloader: [0xab99a6d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9837fc972238b9b0127533f8d6af4804&request_ts=1766940670&track_id=364033073 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.268 [4359.4359] INFO HttpDownloader: [0xab7ff000]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=364033073 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.269 [4359.4359] INFO HttpDownloader: [0xab8dd0c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5abc9b259d691460eb620bd3281bcd4b&request_ts=1766940670&track_id=364033073 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.271 [4359.4359] INFO SampleApp: Playback state changed: Stopped Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.271 [4359.4359] INFO ProtocolHandler: [0xab7c3b08]: Need to send state update Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.271 [4359.4359] INFO QConnectMessageSender: [0xab8faa68]: Sending StateUpdated message Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.272 [4359.4359] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.272 [4359.4359] INFO QwspMessageSender: [0xab8b89d0]: Sending Payload message Dec 28 11:51:10 rivoplus sudo[5459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 11:51:10 rivoplus sudo[5459]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:10 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 28 11:51:10 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 28 11:51:10 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 28 11:51:10 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 28 11:51:10 rivoplus sudo[5459]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:10 rivoplus volumio[3102]: No protocol specified Dec 28 11:51:10 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 11:51:10 rivoplus volumio[3102]: info: Updating Volume Controller Parameters: Device: 5 Name: MERASON Mixer: MERASON Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 28 11:51:10 rivoplus volumio[3102]: info: Disabling external Volume Control Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 11:51:10 rivoplus sudo[5466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 11:51:10 rivoplus sudo[5466]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.497 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478928&eid=364033073&fmt=6&profile=raw&app_id=174516466&cid=2077677&etsp=1766944270&hmac=gqYD5t1lLbPjDw94n4BpPERRZkY Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: playback_stop received, deferred solved Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus sudo[5466]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.516 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 4]: Metadata became available: Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.516 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Title: The Might Mezz (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.516 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Artist: Barry Goldberg Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.516 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Album: Chicago To L.A. (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Album art URL: https://static.qobuz.com/images/covers/tb/yg/jrptkuj6uygtb_600.jpg Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 3]: Metadata became available: Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Title: Guess I Had Enough of You (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Artist: Barry Goldberg Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Album: Chicago To L.A. (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.517 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: Album art URL: https://static.qobuz.com/images/covers/tb/yg/jrptkuj6uygtb_600.jpg Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.530 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 3]: stream information have been fetched Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.531 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478928&eid=364033072&fmt=6&profile=raw&app_id=174516466&cid=2077677&etsp=1766944270&hmac=raZ4xZtUYju52M_EqpkWiJsGz_g Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.531 [4359.4359] INFO UrlAudioSource: [0xab964318]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478928&eid=364033072&fmt=6&profile=raw&app_id=174516466&cid=2077677&etsp=1766944270&hmac=raZ4xZtUYju52M_EqpkWiJsGz_g Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.532 [4359.4359] INFO ContentFetcher: [0xab96a258]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478928&eid=364033072&fmt=6&profile=raw&app_id=174516466&cid=2077677&etsp=1766944270&hmac=raZ4xZtUYju52M_EqpkWiJsGz_g, offset: 0 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.532 [4359.4359] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.532 [4359.4359] INFO SampleApp: [Stream 3]: Stream metadata became available: Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.533 [4359.4359] INFO SampleApp: Title: Guess I Had Enough of You (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.533 [4359.4359] INFO SampleApp: Artist: Barry Goldberg Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.533 [4359.4359] INFO SampleApp: Album: Chicago To L.A. (Remastered) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.533 [4359.4359] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/tb/yg/jrptkuj6uygtb_600.jpg Dec 28 11:51:10 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:10 rivoplus sudo[5472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 11:51:10 rivoplus sudo[5472]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.549 [4359.4359] INFO AudioStreamManager: [0xab7c34b0]: [Stream 4]: stream information have been fetched Dec 28 11:51:10 rivoplus volumio[3102]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Dec 28 11:51:10 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:10 rivoplus sudo[5472]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus sudo[5476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 11:51:10 rivoplus sudo[5476]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.619 [4359.4359] INFO ContentFetcher: [0xab96a258]: Canceling download Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.619 [4359.4359] INFO CloudClient: [0xab7c39a0]: Disconnecting from the cloud Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.619 [4359.4359] INFO CloudClient: [0xab7c39a0]: Connection closed Dec 28 11:51:10 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 28 11:51:10 rivoplus volumio[3102]: info: camilladsp spawned new process with pid 5479, instance 1, run: true Dec 28 11:51:10 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:10 rivoplus volumio[3102]: info: VolumeController:: Volume=undefined Mute =false Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.643 [4359.4359] INFO SampleApp: Stopping Local configuration server Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:51:10 rivoplus qobuz-connect[4359]: 20251228 11:51:10.653 [4359.4359] INFO SampleApp: shat down connection on UNIX socket Dec 28 11:51:10 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:51:10 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 28 11:51:10 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 28 11:51:10 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 28 11:51:10 rivoplus sudo[5476]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:10 rivoplus volumio[3102]: No protocol specified Dec 28 11:51:10 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 11:51:10 rivoplus volumio[3102]: No protocol specified Dec 28 11:51:10 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 28 11:51:10 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:51:10 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 11:51:10 rivoplus volumio[3102]: info: QobuzConnect: setDeactiveState invoked Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:10 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:10 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 11:51:11 rivoplus volumio[3102]: info: Not Reporting Auto name since its the default one Dec 28 11:51:11 rivoplus volumio[3102]: info: Executing endpoint qc_getconfig Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.117 [5486.5486] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.123 [5486.5486] INFO VolumeManager: [0xac48a830]: Setting new playback volume: 75 Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.124 [5486.5486] INFO VolumeManager: [0xac48a830]: Setting new mute state: 0 Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.124 [5486.5486] INFO QobuzConnect: [0xac48b200]: Client initialized! Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.124 [5486.5486] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 28 11:51:11 rivoplus volumio[3102]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 11:51:11 rivoplus volumio[3102]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.152 [5486.5486] INFO LocalConfigManager: [0xac48a110]: Starting Local Configuration server Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.153 [5486.5486] INFO SampleApp: Starting Local configuration server Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.153 [5486.5486] INFO SampleApp: Connected to UNIX socket client 0xac47fed8 Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 28 11:51:11 rivoplus volumio[3102]: No protocol specified Dec 28 11:51:11 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:11 rivoplus qobuz-connect[5486]: 20251228 11:51:11.267 [5486.5486] INFO SampleApp: Playback volume changed: 75 Dec 28 11:51:11 rivoplus sudo[5514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 28 11:51:11 rivoplus sudo[5514]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:11 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:11 rivoplus sudo[5514]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:11 rivoplus sudo[5516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 28 11:51:11 rivoplus sudo[5516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:11 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 28 11:51:11 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 28 11:51:11 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 28 11:51:11 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 28 11:51:11 rivoplus systemd[1]: Started RAAT DAEMON. Dec 28 11:51:11 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:11 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:51:11 rivoplus sudo[5516]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:11 rivoplus volumio[3102]: info: Raat Daemon started successfully Dec 28 11:51:11 rivoplus volumio[3102]: info: Executing endpoint restartRAATSocket Dec 28 11:51:11 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 28 11:51:11 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:11 rivoplus volumio[3102]: 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 28 11:51:12 rivoplus volumio[3102]: info: Executing endpoint metavolumio Dec 28 11:51:12 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 11:51:13 rivoplus volumio[3102]: info: TidalConnect service stoped! Dec 28 11:51:13 rivoplus volumio[3102]: info: TidalConnect service stoped! Dec 28 11:51:13 rivoplus volumio[3102]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 11:51:13 rivoplus volumio[3102]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 11:51:13 rivoplus sudo[5596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 28 11:51:13 rivoplus sudo[5596]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:13 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 28 11:51:13 rivoplus sudo[5596]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:14 rivoplus volumio[3102]: info: Executing endpoint tc_getconfig Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 28 11:51:14 rivoplus vtcs[5598]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 28 11:51:14 rivoplus vtcs[5598]: STARTED TidalConnect services. Dec 28 11:51:14 rivoplus volumio[3102]: info: Executing endpoint tc_connect Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 28 11:51:14 rivoplus volumio[3102]: info: Connecting to TidalConnect Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 28 11:51:14 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:14 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:14 rivoplus volumio[3102]: info: RAAT: Requesting Headphone Status Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 28 11:51:14 rivoplus volumio[3102]: info: MCU Signalled Headphone Mode Disabled Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 28 11:51:14 rivoplus volumio[3102]: info: Reporting Headphone State: false Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:14 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:14 rivoplus ntpd[3390]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 28 11:51:14 rivoplus volumio[3102]: info: VolumeController::SetAlsaVolume100 Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:51:14 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:14 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:51:14 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:51:14 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:14 rivoplus volumio[3102]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Dec 28 11:51:14 rivoplus volumio[3102]: xcb_connection_has_error() returned true Dec 28 11:51:16 rivoplus volumio[3102]: info: TidalConnect service started! Dec 28 11:51:16 rivoplus volumio[3102]: info: Executing endpoint metavolumio Dec 28 11:51:16 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 11:51:19 rivoplus volumio[3102]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Dec 28 11:51:19 rivoplus sudo[5623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 11:51:19 rivoplus sudo[5623]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:19 rivoplus sudo[5623]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:19 rivoplus volumio[3102]: info: MPD Permissions set Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus sudo[5624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 11:51:19 rivoplus sudo[5624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:19 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 28 11:51:19 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 11:51:19 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:19 rivoplus systemd[1]: mpd.service: Succeeded. Dec 28 11:51:19 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 28 11:51:19 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:51:19 rivoplus systemd[1]: Starting Music Player Daemon... Dec 28 11:51:19 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:19 rivoplus sudo[5633]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 11:51:19 rivoplus sudo[5633]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:19 rivoplus sudo[5633]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:21 rivoplus mpd[5644]: Dec 28 11:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 11:51:21 rivoplus systemd[1]: Started Music Player Daemon. Dec 28 11:51:21 rivoplus sudo[5624]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:21 rivoplus volumio[3102]: error: updateQueue error: null Dec 28 11:51:24 rivoplus sudo[5665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 11:51:24 rivoplus sudo[5665]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 11:51:24 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 28 11:51:24 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 28 11:51:24 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 28 11:51:24 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 28 11:51:24 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 11:51:24 rivoplus sudo[5665]: pam_unix(sudo:session): session closed for user root Dec 28 11:51:24 rivoplus systemd[1]: Started MPD Monitor Service. Dec 28 11:51:24 rivoplus volumio[3102]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 11:51:24 rivoplus mpd_monitor.sh[5667]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 11:51:24 rivoplus volumio[3102]: info: Successfully started MPD Monitor Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPlay Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::play index undefined Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 11:51:28 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::startPlaybackTimer Dec 28 11:51:28 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 11:51:28 rivoplus volumio[3102]: verbose: ControllerMpd::sendMpdCommand play Dec 28 11:51:28 rivoplus volumio[3102]: info: sendMpdCommand play took 3 milliseconds Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::servicePushState Dec 28 11:51:28 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:28 rivoplus volumio[3102]: verbose: STATE SERVICE {"status":"play"} Dec 28 11:51:28 rivoplus volumio[3102]: verbose: CURRENT POSITION 0 Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::syncState stateService play Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreStateMachine::pushState Dec 28 11:51:28 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::volumioPushState Dec 28 11:51:28 rivoplus volumio[3102]: info: CoreCommandRouter::volumioGetState Dec 28 11:51:28 rivoplus volumio[3102]: info: CorePlayQueue::getTrack 0 Dec 28 11:51:28 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output update for this device Dec 28 11:51:28 rivoplus volumio[3102]: info: MRS: Pushing multiroomSync output Dec 28 11:51:28 rivoplus volumio[3102]: info: [1766940688132] [RadioParadise] getMetadata started with url undefined Dec 28 11:51:28 rivoplus volumio[3102]: info: Signalling Playback active due to playback status change Dec 28 11:51:28 rivoplus volumio[3102]: info: Updating RAAT Signal Path Dec 28 11:51:28 rivoplus volumio[3102]: info: [1766940688169] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Dec 28 11:51:28 rivoplus volumio[3102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 11:51:28 rivoplus volumio[3102]: TypeError: Cannot read property 'toUpperCase' of undefined Dec 28 11:51:28 rivoplus volumio[3102]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Dec 28 11:51:28 rivoplus volumio[3102]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Dec 28 11:51:28 rivoplus volumio[3102]: at ClientRequest.emit (events.js:400:28) Dec 28 11:51:28 rivoplus volumio[3102]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Dec 28 11:51:28 rivoplus volumio[3102]: at TLSSocket.emit (events.js:400:28) Dec 28 11:51:28 rivoplus volumio[3102]: at emitErrorNT (internal/streams/destroy.js:106:8) Dec 28 11:51:28 rivoplus volumio[3102]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Dec 28 11:51:28 rivoplus volumio[3102]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 28 11:51:28 rivoplus volumio[3102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 11:51:28 rivoplus sudo[5689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 11:50 Dec 28 11:51:28 rivoplus sudo[5689]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:44:55 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="af8df74278231e4f9fb9f04db56c320f"