-- Logs begin at Fri 2025-12-26 14:15:09 JST, end at Fri 2025-12-26 16:16:02 JST. -- Dec 26 16:15:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:04 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:08 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:41 rivoplus volumio[3192]: info: Received OAUTH Data Dec 26 16:15:41 rivoplus volumio[3192]: info: Refreshing QOBUZ token Dec 26 16:15:41 rivoplus volumio[3192]: info: Access Token successfully retrieved Dec 26 16:15:41 rivoplus volumio[3192]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 16:15:41 rivoplus volumio[3192]: info: [1766733341366] CoreMusicLibrary::Adding element QOBUZ Dec 26 16:15:41 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:15:41 rivoplus volumio[3192]: Cannot find translation for source YouTube2 Dec 26 16:15:41 rivoplus volumio[3192]: Cannot find translation for source QOBUZ Dec 26 16:15:41 rivoplus sudo[22529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:15:41 rivoplus sudo[22529]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:41 rivoplus sudo[22529]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:41 rivoplus sudo[22533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:15:41 rivoplus sudo[22533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:41 rivoplus sudo[22533]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:41 rivoplus volumio[3192]: verbose: New Socket.io Connection to 192.168.3.106 from 192.168.3.38 UA: Mozilla/5.0 (Linux; Android 14; A302OP Build/UKQ1.230924.001; 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 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:42 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: Retrieving Cloud Streaming UI Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 16:15:42 rivoplus volumio[3192]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 26 16:15:42 rivoplus volumio[3192]: info: Received Get System Info Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:15:42 rivoplus volumio[3192]: info: Discovery: Getting this device information Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:42 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:42 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:42 rivoplus volumio[3192]: info: Listing playlists Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:15:42 rivoplus volumio[3192]: info: Getting Tidal Cloud Configuration Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: Getting Qobuz Cloud Configuration Dec 26 16:15:42 rivoplus volumio[3192]: info: Asking plugin for UI Config Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: Getting Spotify Cloud Configuration Dec 26 16:15:42 rivoplus volumio[3192]: info: Asking plugin for UI Config Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: Saving Spotify Acccount Dec 26 16:15:42 rivoplus volumio[3192]: info: Got it Dec 26 16:15:42 rivoplus volumio[3192]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 26 16:15:42 rivoplus volumio[3192]: info: Got Tidal Cloud Configuration Dec 26 16:15:42 rivoplus volumio[3192]: info: Got it Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:42 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:15:43 rivoplus volumio[3192]: info: Received Get System Info Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:15:43 rivoplus volumio[3192]: info: Discovery: Getting this device information Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:43 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:15:43 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 26 16:15:44 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:15:44 rivoplus volumio[3192]: info: Received Get System Info Dec 26 16:15:44 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:15:44 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:15:44 rivoplus volumio[3192]: info: Discovery: Getting this device information Dec 26 16:15:44 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:44 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:44 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:15:45 rivoplus volumio[3192]: info: Disabling MyMusic plugin multiroom Dec 26 16:15:45 rivoplus volumio[3192]: info: Disabling plugin multiroom Dec 26 16:15:45 rivoplus volumio[3192]: info: Preparing to generate the ALSA configuration file Dec 26 16:15:45 rivoplus volumio[3192]: info: Asound.conf file written Dec 26 16:15:45 rivoplus sudo[22554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 16:15:45 rivoplus sudo[22554]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:45 rivoplus sudo[22554]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:45 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Dec 26 16:15:45 rivoplus kernel: Fine spdif sysclk setting range(0~2000000), 24575987 Dec 26 16:15:45 rivoplus kernel: out of value, fixed it Dec 26 16:15:45 rivoplus kernel: id=0 set inskew=0 Dec 26 16:15:45 rivoplus volumio[3192]: No protocol specified Dec 26 16:15:45 rivoplus volumio[3192]: xcb_connection_has_error() returned true Dec 26 16:15:45 rivoplus volumio[3192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Dec 26 16:15:45 rivoplus volumio[3192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Dec 26 16:15:45 rivoplus volumio[3192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Dec 26 16:15:45 rivoplus volumio[3192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Dec 26 16:15:45 rivoplus volumio[3192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Dec 26 16:15:45 rivoplus volumio[3192]: info: Output device has changed, restarting MPD Dec 26 16:15:45 rivoplus sudo[22559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 16:15:45 rivoplus sudo[22559]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:45 rivoplus sudo[22559]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:45 rivoplus volumio[3192]: info: Output device has changed, restarting Shairport Sync Dec 26 16:15:45 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:45 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:15:45 rivoplus sudo[22562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 16:15:45 rivoplus sudo[22562]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:45 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 26 16:15:45 rivoplus volumio[3192]: No protocol specified Dec 26 16:15:45 rivoplus volumio[3192]: xcb_connection_has_error() returned true Dec 26 16:15:45 rivoplus volumio[3192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 16:15:45 rivoplus volumio[3192]: No protocol specified Dec 26 16:15:45 rivoplus volumio[3192]: xcb_connection_has_error() returned true Dec 26 16:15:45 rivoplus volumio[3192]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 16:15:45 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:45 rivoplus volumio[3192]: info: QobuzConnect: setDeactiveState invoked Dec 26 16:15:45 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:45 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:45 rivoplus volumio[3192]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 26 16:15:45 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:45 rivoplus vtcs[26675]: [2025-12-26 16:15:45.827] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 26 16:15:45 rivoplus vtcs[26675]: [2025-12-26 16:15:45.827] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 26 16:15:45 rivoplus systemd[1]: mpd.service: Succeeded. Dec 26 16:15:45 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 26 16:15:45 rivoplus volumio[3192]: info: Done. Dec 26 16:15:45 rivoplus systemd[1]: Starting Music Player Daemon... Dec 26 16:15:45 rivoplus sudo[22570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 16:15:45 rivoplus sudo[22570]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:45 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 26 16:15:45 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 26 16:15:45 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 26 16:15:45 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 26 16:15:45 rivoplus sudo[22582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 16:15:45 rivoplus sudo[22582]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus sudo[22570]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus sudo[22582]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus sudo[22571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 16:15:46 rivoplus sudo[22571]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus sudo[22571]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 16:15:46 rivoplus sudo[22593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 16:15:46 rivoplus sudo[22593]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus sudo[22593]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus volumio[3192]: info: MPD Permissions set Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus sudo[22596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus sudo[22596]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 26 16:15:46 rivoplus qobuz-connect[26576]: 20251226 16:15:46.255 [26576.26576] INFO SampleApp: Stopping Local configuration server Dec 26 16:15:46 rivoplus qobuz-connect[26576]: 20251226 16:15:46.266 [26576.26576] INFO SampleApp: shat down connection on UNIX socket Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus volumio[3192]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 26 16:15:46 rivoplus volumio[3192]: info: QobuzConnect: setDeactiveState invoked Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:46 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:46 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 26 16:15:46 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 26 16:15:46 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 26 16:15:46 rivoplus sudo[22596]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:15:46 rivoplus volumio[3192]: info: Not Reporting Auto name since its the default one Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 16:15:46 rivoplus volumio[3192]: info: Starting Shairport Sync Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 26 16:15:46 rivoplus sudo[22615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 16:15:46 rivoplus sudo[22615]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 16:15:46 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 26 16:15:46 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 16:15:46 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 16:15:46 rivoplus sudo[22615]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus volumio[3192]: No protocol specified Dec 26 16:15:46 rivoplus volumio[3192]: xcb_connection_has_error() returned true Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:46 rivoplus volumio[3192]: error: MPD error: Error: This socket has been ended by the other party Dec 26 16:15:46 rivoplus sudo[22623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 26 16:15:46 rivoplus volumio[3192]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 26 16:15:46 rivoplus volumio[3192]: error: MPD error: Error: This socket has been ended by the other party Dec 26 16:15:46 rivoplus volumio[3192]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 26 16:15:46 rivoplus volumio[3192]: error: MPD error: Error: This socket has been ended by the other party Dec 26 16:15:46 rivoplus volumio[3192]: error: This socket has been ended by the other party {"code":"EPIPE"} Dec 26 16:15:46 rivoplus sudo[22623]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus volumio[3192]: info: Shairport-Sync Started Dec 26 16:15:46 rivoplus volumio[3192]: info: Executing endpoint qc_getconfig Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 16:15:46 rivoplus sudo[22623]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus sudo[22626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 26 16:15:46 rivoplus sudo[22626]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.743 [22604.22604] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 26 16:15:46 rivoplus volumio[3192]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 26 16:15:46 rivoplus volumio[3192]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 26 16:15:46 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 26 16:15:46 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 26 16:15:46 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 26 16:15:46 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.774 [22604.22604] INFO VolumeManager: [0xac841240]: Setting new playback volume: 75 Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.775 [22604.22604] INFO VolumeManager: [0xac841240]: Setting new mute state: 0 Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.776 [22604.22604] INFO QobuzConnect: [0xac841c10]: Client initialized! Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.776 [22604.22604] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 26 16:15:46 rivoplus systemd[1]: Started RAAT DAEMON. Dec 26 16:15:46 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 16:15:46 rivoplus sudo[22626]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.806 [22604.22604] INFO LocalConfigManager: [0xac840b20]: Starting Local Configuration server Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.806 [22604.22604] INFO SampleApp: Starting Local configuration server Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.807 [22604.22604] INFO SampleApp: Connected to UNIX socket client 0xac836ed8 Dec 26 16:15:46 rivoplus volumio[3192]: info: Raat Daemon started successfully Dec 26 16:15:46 rivoplus qobuz-connect[22604]: 20251226 16:15:46.891 [22604.22604] INFO SampleApp: Playback volume changed: 75 Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:46 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:46 rivoplus volumio[3192]: info: Executing endpoint restartRAATSocket Dec 26 16:15:46 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 26 16:15:46 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:47 rivoplus volumio[3192]: 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 26 16:15:48 rivoplus mpd[22595]: Dec 26 16:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 16:15:48 rivoplus systemd[1]: Started Music Player Daemon. Dec 26 16:15:48 rivoplus sudo[22562]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:48 rivoplus volumio[3192]: error: updateQueue error: null Dec 26 16:15:49 rivoplus volumio[3192]: info: TidalConnect service stoped! Dec 26 16:15:49 rivoplus volumio[3192]: info: TidalConnect service stoped! Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 26 16:15:49 rivoplus sudo[22656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 26 16:15:49 rivoplus sudo[22656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:49 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 26 16:15:49 rivoplus sudo[22656]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: Executing endpoint tc_getconfig Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 26 16:15:49 rivoplus vtcs[22658]: STARTING TidalConnect services, version: 1.5.2.56 Dec 26 16:15:49 rivoplus volumio[3192]: info: RAAT: Requesting Headphone Status Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 26 16:15:49 rivoplus vtcs[22658]: STARTED TidalConnect services. Dec 26 16:15:49 rivoplus volumio[3192]: info: Executing endpoint tc_connect Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 26 16:15:49 rivoplus volumio[3192]: info: Connecting to TidalConnect Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreStateMachine::pushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received tidalconnect Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreStateMachine::pushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received tidalconnect Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating RAAT Signal Path Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating RAAT Signal Path Dec 26 16:15:49 rivoplus volumio[3192]: info: MCU Signalled Headphone Mode Disabled Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 26 16:15:49 rivoplus volumio[3192]: info: Reporting Headphone State: false Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating RAAT Signal Path Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreStateMachine::pushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioPushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output update for this device Dec 26 16:15:49 rivoplus volumio[3192]: info: MRS: Pushing multiroomSync output Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received raat Dec 26 16:15:49 rivoplus volumio[3192]: info: CoreCommandRouter::servicePushState Dec 26 16:15:49 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:15:49 rivoplus volumio[3192]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received raat Dec 26 16:15:49 rivoplus volumio[3192]: info: Updating RAAT Signal Path Dec 26 16:15:50 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:51 rivoplus sudo[22680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 16:15:51 rivoplus sudo[22680]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:51 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 26 16:15:51 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 26 16:15:51 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 26 16:15:51 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 26 16:15:51 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:51 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 16:15:51 rivoplus systemd[1]: Started MPD Monitor Service. Dec 26 16:15:51 rivoplus sudo[22680]: pam_unix(sudo:session): session closed for user root Dec 26 16:15:51 rivoplus volumio[3192]: info: Successfully started MPD Monitor Dec 26 16:15:51 rivoplus mpd_monitor.sh[22682]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 16:15:51 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 26 16:15:52 rivoplus volumio[3192]: info: TidalConnect service started! Dec 26 16:15:52 rivoplus volumio[3192]: info: Disabling MyMusic plugin upnp Dec 26 16:15:52 rivoplus sudo[22687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 26 16:15:52 rivoplus sudo[22687]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 16:15:52 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 26 16:15:52 rivoplus volumio[3192]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 16:15:52 rivoplus volumio[3192]: error: Upnp client error: Error: This socket has been ended by the other party Dec 26 16:15:54 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:15:58 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:16:00 rivoplus volumio[3192]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 16:16:00 rivoplus volumio[3192]: info: Received Get System Version Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:16:00 rivoplus volumio[3192]: info: Received Get System Info Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:16:00 rivoplus volumio[3192]: info: Discovery: Getting this device information Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::volumioGetState Dec 26 16:16:00 rivoplus volumio[3192]: info: CorePlayQueue::getTrack 0 Dec 26 16:16:00 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:16:01 rivoplus volumio[3192]: info: Enabling MyMusic plugin upnp Dec 26 16:16:01 rivoplus volumio[3192]: info: Enabling plugin upnp Dec 26 16:16:01 rivoplus volumio[3192]: info: Loading plugin "upnp"... Dec 26 16:16:01 rivoplus volumio[3192]: info: [1766733361385] Starting Upmpd Daemon Dec 26 16:16:01 rivoplus volumio[3192]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 16:16:01 rivoplus volumio[3192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:16:01 rivoplus volumio[3192]: Error: listen EADDRINUSE: address already in use :::6599 Dec 26 16:16:01 rivoplus volumio[3192]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Dec 26 16:16:01 rivoplus volumio[3192]: at listenInCluster (net.js:1379:12) Dec 26 16:16:01 rivoplus volumio[3192]: at Server.listen (net.js:1465:7) Dec 26 16:16:01 rivoplus volumio[3192]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 26 16:16:01 rivoplus volumio[3192]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 26 16:16:01 rivoplus volumio[3192]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 26 16:16:01 rivoplus volumio[3192]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 26 16:16:01 rivoplus volumio[3192]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 26 16:16:01 rivoplus volumio[3192]: code: 'EADDRINUSE', Dec 26 16:16:01 rivoplus volumio[3192]: errno: -98, Dec 26 16:16:01 rivoplus volumio[3192]: syscall: 'listen', Dec 26 16:16:01 rivoplus volumio[3192]: address: '::', Dec 26 16:16:01 rivoplus volumio[3192]: port: 6599 Dec 26 16:16:01 rivoplus volumio[3192]: } Dec 26 16:16:01 rivoplus volumio[3192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:16:02 rivoplus sudo[22715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 16:15 Dec 26 16:16:02 rivoplus sudo[22715]: 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"