-- Logs begin at Thu 2025-02-27 20:29:52 JST, end at Sat 2025-05-17 11:58:13 JST. -- May 17 11:57:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:10 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:14 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:18 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:22 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:26 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:27 rivoplus volumio[3177]: info: Disabling MyMusic plugin multiroom May 17 11:57:27 rivoplus volumio[3177]: info: Disabling plugin multiroom May 17 11:57:27 rivoplus volumio[3177]: info: Preparing to generate the ALSA configuration file May 17 11:57:27 rivoplus volumio[3177]: info: Asound.conf file written May 17 11:57:27 rivoplus sudo[7639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 17 11:57:27 rivoplus sudo[7639]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus sudo[7639]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus volumio[3177]: No protocol specified May 17 11:57:27 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:27 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:57:27 rivoplus volumio[3177]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" May 17 11:57:27 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:57:27 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:57:27 rivoplus volumio[3177]: No state is present for card Device May 17 11:57:27 rivoplus volumio[3177]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" May 17 11:57:27 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:57:27 rivoplus volumio[3177]: No state is present for card Device May 17 11:57:27 rivoplus volumio[3177]: info: Output device has changed, restarting MPD May 17 11:57:27 rivoplus sudo[7644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 17 11:57:27 rivoplus sudo[7644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus volumio[3177]: info: Output device has changed, restarting Shairport Sync May 17 11:57:27 rivoplus sudo[7644]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:57:27 rivoplus sudo[7647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 17 11:57:27 rivoplus sudo[7647]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus systemd[1]: Stopping Music Player Daemon... May 17 11:57:27 rivoplus volumio[3177]: No protocol specified May 17 11:57:27 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:27 rivoplus volumio[3177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 17 11:57:27 rivoplus volumio[3177]: No protocol specified May 17 11:57:27 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:27 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus systemd[1]: mpd.service: Succeeded. May 17 11:57:27 rivoplus systemd[1]: Stopped Music Player Daemon. May 17 11:57:27 rivoplus volumio[3177]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus vtcs[5337]: [2025-05-17 11:57:27.379] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 17 11:57:27 rivoplus vtcs[5337]: [2025-05-17 11:57:27.380] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 17 11:57:27 rivoplus vtcs[5337]: [2025-05-17 11:57:27.380] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 17 11:57:27 rivoplus systemd[1]: Starting Music Player Daemon... May 17 11:57:27 rivoplus volumio[3177]: info: Done. May 17 11:57:27 rivoplus sudo[7663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:57:27 rivoplus sudo[7663]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:27 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:27 rivoplus systemd[1]: vtcs.service: Succeeded. May 17 11:57:27 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 17 11:57:27 rivoplus sudo[7667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:57:27 rivoplus sudo[7667]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus sudo[7663]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus volumio[3177]: info: MPD Permissions set May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:27 rivoplus sudo[7654]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 17 11:57:27 rivoplus sudo[7654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus sudo[7667]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus sudo[7654]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: Starting Shairport Sync May 17 11:57:27 rivoplus sudo[7682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 17 11:57:27 rivoplus sudo[7682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:27 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 17 11:57:27 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 17 11:57:27 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 17 11:57:27 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:27 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:27 rivoplus sudo[7682]: pam_unix(sudo:session): session closed for user root May 17 11:57:27 rivoplus volumio[3177]: info: Shairport-Sync Started May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 11:57:27 rivoplus volumio[3177]: info: Not Reporting Auto name since its the default one May 17 11:57:27 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 17 11:57:28 rivoplus volumio[3177]: No protocol specified May 17 11:57:28 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:28 rivoplus sudo[7698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 17 11:57:28 rivoplus sudo[7698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:28 rivoplus sudo[7698]: pam_unix(sudo:session): session closed for user root May 17 11:57:28 rivoplus sudo[7700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 17 11:57:28 rivoplus sudo[7700]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:28 rivoplus systemd[1]: Stopping RAAT DAEMON... May 17 11:57:28 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 17 11:57:28 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 17 11:57:28 rivoplus systemd[1]: Stopped RAAT DAEMON. May 17 11:57:28 rivoplus systemd[1]: Started RAAT DAEMON. May 17 11:57:28 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:28 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:28 rivoplus sudo[7700]: pam_unix(sudo:session): session closed for user root May 17 11:57:28 rivoplus volumio[3177]: info: Raat Daemon started successfully May 17 11:57:28 rivoplus volumio[3177]: info: Executing endpoint restartRAATSocket May 17 11:57:28 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 17 11:57:28 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:57:28 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 17 11:57:29 rivoplus mpd[7678]: May 17 11:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 17 11:57:29 rivoplus systemd[1]: Started Music Player Daemon. May 17 11:57:29 rivoplus sudo[7647]: pam_unix(sudo:session): session closed for user root May 17 11:57:29 rivoplus volumio[3177]: error: updateQueue error: null May 17 11:57:30 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:30 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:57:30 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:57:30 rivoplus volumio[3177]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 17 11:57:30 rivoplus volumio[3177]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 17 11:57:30 rivoplus sudo[7725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 17 11:57:30 rivoplus sudo[7725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:30 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 17 11:57:30 rivoplus sudo[7725]: pam_unix(sudo:session): session closed for user root May 17 11:57:31 rivoplus volumio[3177]: info: RAAT: Requesting Headphone Status May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 17 11:57:31 rivoplus volumio[3177]: info: Executing endpoint tc_getconfig May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 17 11:57:31 rivoplus volumio[3177]: info: MCU Signalled Headphone Mode Disabled May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 17 11:57:31 rivoplus vtcs[7727]: STARTING TidalConnect services, version: 1.4.0.34 May 17 11:57:31 rivoplus volumio[3177]: info: Reporting Headphone State: false May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:31 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:57:31 rivoplus vtcs[7727]: STARTED TidalConnect services. May 17 11:57:31 rivoplus volumio[3177]: info: Executing endpoint tc_connect May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 17 11:57:31 rivoplus volumio[3177]: info: Connecting to TidalConnect May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::servicePushState May 17 11:57:31 rivoplus volumio[3177]: info: CoreStateMachine::pushState May 17 11:57:31 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::volumioPushState May 17 11:57:31 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:57:31 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:57:31 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:57:31 rivoplus volumio[3177]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::servicePushState May 17 11:57:31 rivoplus volumio[3177]: info: CoreStateMachine::pushState May 17 11:57:31 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:57:31 rivoplus volumio[3177]: info: CoreCommandRouter::volumioPushState May 17 11:57:31 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:57:31 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:57:31 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:57:31 rivoplus volumio[3177]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 17 11:57:31 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:57:31 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:57:33 rivoplus volumio[3177]: info: Executing endpoint metavolumio May 17 11:57:33 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 11:57:33 rivoplus volumio[3177]: info: TidalConnect service started! May 17 11:57:34 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:34 rivoplus volumio[3177]: info: Disabling MyMusic plugin cd_controller May 17 11:57:34 rivoplus volumio[3177]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 17 11:57:34 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 11:57:34 rivoplus volumio[3177]: info: Stopping UDEV Watcher for CD May 17 11:57:34 rivoplus volumio[3177]: info: Disabling plugin cd_controller May 17 11:57:34 rivoplus volumio[3177]: info: Done. May 17 11:57:38 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:42 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:46 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:50 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:52 rivoplus volumio[3177]: info: Enabling MyMusic plugin cd_controller May 17 11:57:52 rivoplus volumio[3177]: info: Enabling plugin cd_controller May 17 11:57:52 rivoplus volumio[3177]: info: Loading plugin "cd_controller"... May 17 11:57:53 rivoplus volumio[3177]: info: PLUGIN START: cd_controller May 17 11:57:53 rivoplus volumio[3177]: info: Preparing CD Folders May 17 11:57:53 rivoplus volumio[3177]: info: Adding CD REST API Endpoints May 17 11:57:53 rivoplus volumio[3177]: info: Updating cdPostRip REST Endpoint for plugin: music_service/cd_controller May 17 11:57:53 rivoplus volumio[3177]: info: Starting UDEV Watcher for CD May 17 11:57:53 rivoplus volumio[3177]: info: Detecting CD presence with UDEV May 17 11:57:53 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 17 11:57:53 rivoplus volumio[3177]: info: Done. May 17 11:57:54 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:58 rivoplus volumio[3177]: info: Enabling MyMusic plugin multiroom May 17 11:57:58 rivoplus volumio[3177]: info: Enabling plugin multiroom May 17 11:57:58 rivoplus volumio[3177]: info: Loading plugin "multiroom"... May 17 11:57:58 rivoplus volumio[3177]: info: Applying required configuration parameters for plugin multiroom May 17 11:57:58 rivoplus sudo[7806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 17 11:57:58 rivoplus sudo[7806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7806]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus volumio[3177]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 17 11:57:58 rivoplus volumio[3177]: info: MRS: MultiRoom plugin initialized May 17 11:57:58 rivoplus volumio[3177]: info: MRS: STOPPING SNAPCLIENT May 17 11:57:58 rivoplus volumio[3177]: info: MRS: Snap server stop May 17 11:57:58 rivoplus sudo[7822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 17 11:57:58 rivoplus sudo[7822]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus volumio[3177]: info: MRS: STOPPING volumioStreaming May 17 11:57:58 rivoplus sudo[7825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 17 11:57:58 rivoplus sudo[7825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7822]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus sudo[7827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 17 11:57:58 rivoplus sudo[7827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7825]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus volumio[3177]: info: Preparing to generate the ALSA configuration file May 17 11:57:58 rivoplus sudo[7827]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus sudo[7832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 17 11:57:58 rivoplus sudo[7832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7832]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:57:58 rivoplus volumio[3177]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 17 11:57:58 rivoplus volumio[3177]: info: Reading ALSA contributions from plugins. May 17 11:57:58 rivoplus volumio[3177]: info: MRS: volumioStreaming STOPPED May 17 11:57:58 rivoplus volumio[3177]: info: MRS: SNAPSERVER STOPPED May 17 11:57:58 rivoplus volumio[3177]: info: MRS: SNAPCLIENT STOPPED May 17 11:57:58 rivoplus volumio[3177]: info: MRS: Removed streaming files May 17 11:57:58 rivoplus volumio[3177]: info: Asound.conf file written May 17 11:57:58 rivoplus sudo[7835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 17 11:57:58 rivoplus sudo[7835]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7835]: pam_unix(sudo:session): session closed for user root May 17 11:57:58 rivoplus volumio[3177]: No protocol specified May 17 11:57:58 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:58 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:57:58 rivoplus volumio[3177]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" May 17 11:57:58 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:57:58 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:57:58 rivoplus volumio[3177]: No state is present for card Device May 17 11:57:58 rivoplus volumio[3177]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" May 17 11:57:58 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:57:58 rivoplus volumio[3177]: No state is present for card Device May 17 11:57:58 rivoplus volumio[3177]: info: Output device has changed, restarting MPD May 17 11:57:58 rivoplus volumio[3177]: info: Output device has changed, restarting Shairport Sync May 17 11:57:58 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:58 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:57:58 rivoplus sudo[7840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 17 11:57:58 rivoplus sudo[7840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 17 11:57:58 rivoplus sudo[7842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:58 rivoplus sudo[7840]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus systemd[1]: Stopping Music Player Daemon... May 17 11:57:59 rivoplus volumio[3177]: No protocol specified May 17 11:57:59 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:59 rivoplus volumio[3177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 17 11:57:59 rivoplus volumio[3177]: No protocol specified May 17 11:57:59 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus systemd[1]: mpd.service: Succeeded. May 17 11:57:59 rivoplus systemd[1]: Stopped Music Player Daemon. May 17 11:57:59 rivoplus systemd[1]: Starting Music Player Daemon... May 17 11:57:59 rivoplus volumio[3177]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus vtcs[7727]: [2025-05-17 11:57:59.065] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 17 11:57:59 rivoplus vtcs[7727]: [2025-05-17 11:57:59.065] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus sudo[7860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:57:59 rivoplus sudo[7860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus volumio[3177]: info: PLUGIN START: multiroom May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output for this device May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:57:59 rivoplus volumio[3177]: info: Adding audio output: May 17 11:57:59 rivoplus volumio[3177]: error: Can't add: May 17 11:57:59 rivoplus volumio[3177]: info: Adding audio output: May 17 11:57:59 rivoplus volumio[3177]: error: Can't add: May 17 11:57:59 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 17 11:57:59 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 17 11:57:59 rivoplus systemd[1]: vtcs.service: Succeeded. May 17 11:57:59 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 17 11:57:59 rivoplus sudo[7860]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus sudo[7849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 17 11:57:59 rivoplus sudo[7849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus sudo[7849]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:59 rivoplus sudo[7872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:59 rivoplus sudo[7872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus volumio[3177]: info: MPD Permissions set May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus sudo[7872]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: Done. May 17 11:57:59 rivoplus volumio[3177]: info: Starting Shairport Sync May 17 11:57:59 rivoplus volumio[3177]: info: MRS: Found cast device: BRAVIA-4K-VH2-b2f566d2288fab6cd6595b08be37d2fe May 17 11:57:59 rivoplus volumio[3177]: info: Adding audio output: May 17 11:57:59 rivoplus volumio[3177]: error: Can't add: May 17 11:57:59 rivoplus sudo[7884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 17 11:57:59 rivoplus sudo[7884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 17 11:57:59 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 17 11:57:59 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 17 11:57:59 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 17 11:57:59 rivoplus sudo[7884]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 11:57:59 rivoplus volumio[3177]: info: Not Reporting Auto name since its the default one May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:59 rivoplus volumio[3177]: info: Shairport-Sync Started May 17 11:57:59 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 17 11:57:59 rivoplus volumio[3177]: No protocol specified May 17 11:57:59 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:57:59 rivoplus sudo[7897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 17 11:57:59 rivoplus sudo[7897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus sudo[7897]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus sudo[7899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 17 11:57:59 rivoplus sudo[7899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:57:59 rivoplus systemd[1]: Stopping RAAT DAEMON... May 17 11:57:59 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 17 11:57:59 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 17 11:57:59 rivoplus systemd[1]: Stopped RAAT DAEMON. May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:57:59 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:57:59 rivoplus systemd[1]: Started RAAT DAEMON. May 17 11:57:59 rivoplus sudo[7899]: pam_unix(sudo:session): session closed for user root May 17 11:57:59 rivoplus volumio[3177]: info: Raat Daemon started successfully May 17 11:58:00 rivoplus volumio[3177]: info: Executing endpoint restartRAATSocket May 17 11:58:00 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 17 11:58:00 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:00 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 17 11:58:01 rivoplus mpd[7873]: May 17 11:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 17 11:58:01 rivoplus systemd[1]: Started Music Player Daemon. May 17 11:58:01 rivoplus sudo[7842]: pam_unix(sudo:session): session closed for user root May 17 11:58:01 rivoplus volumio[3177]: error: updateQueue error: null May 17 11:58:02 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:58:02 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:58:02 rivoplus volumio[3177]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 17 11:58:02 rivoplus volumio[3177]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 17 11:58:02 rivoplus sudo[7923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 17 11:58:02 rivoplus sudo[7923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:02 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:58:02 rivoplus sudo[7923]: pam_unix(sudo:session): session closed for user root May 17 11:58:02 rivoplus volumio[3177]: info: Disabling MyMusic plugin multiroom May 17 11:58:02 rivoplus volumio[3177]: info: Disabling plugin multiroom May 17 11:58:02 rivoplus volumio[3177]: info: Preparing to generate the ALSA configuration file May 17 11:58:02 rivoplus volumio[3177]: info: Asound.conf file written May 17 11:58:02 rivoplus sudo[7930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 17 11:58:02 rivoplus sudo[7930]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:02 rivoplus sudo[7930]: pam_unix(sudo:session): session closed for user root May 17 11:58:02 rivoplus volumio[3177]: No protocol specified May 17 11:58:02 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:58:02 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:58:02 rivoplus volumio[3177]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" May 17 11:58:02 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:58:02 rivoplus volumio[3177]: No state is present for card AMLAUGESOUNDMP1 May 17 11:58:02 rivoplus volumio[3177]: No state is present for card Device May 17 11:58:02 rivoplus volumio[3177]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" May 17 11:58:02 rivoplus volumio[3177]: Hardware is initialized using a generic method May 17 11:58:02 rivoplus volumio[3177]: No state is present for card Device May 17 11:58:02 rivoplus volumio[3177]: info: Output device has changed, restarting MPD May 17 11:58:02 rivoplus sudo[7935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 17 11:58:02 rivoplus sudo[7935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:02 rivoplus volumio[3177]: info: Output device has changed, restarting Shairport Sync May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:58:02 rivoplus sudo[7935]: pam_unix(sudo:session): session closed for user root May 17 11:58:02 rivoplus sudo[7938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 17 11:58:02 rivoplus sudo[7938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:02 rivoplus systemd[1]: Stopping Music Player Daemon... May 17 11:58:02 rivoplus volumio[3177]: No protocol specified May 17 11:58:02 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:58:02 rivoplus volumio[3177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 17 11:58:02 rivoplus volumio[3177]: No protocol specified May 17 11:58:02 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:58:02 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:02 rivoplus systemd[1]: mpd.service: Succeeded. May 17 11:58:02 rivoplus systemd[1]: Stopped Music Player Daemon. May 17 11:58:02 rivoplus systemd[1]: Starting Music Player Daemon... May 17 11:58:02 rivoplus volumio[3177]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:02 rivoplus volumio[3177]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 17 11:58:02 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus sudo[7949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:58:03 rivoplus sudo[7949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus volumio[3177]: info: Done. May 17 11:58:03 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 17 11:58:03 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 17 11:58:03 rivoplus systemd[1]: vtcs.service: Succeeded. May 17 11:58:03 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 17 11:58:03 rivoplus sudo[7949]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus sudo[7946]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 17 11:58:03 rivoplus sudo[7946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus volumio[3177]: info: RAAT: Requesting Headphone Status May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 17 11:58:03 rivoplus sudo[7946]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:03 rivoplus sudo[7956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 17 11:58:03 rivoplus sudo[7956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus volumio[3177]: info: MPD Permissions set May 17 11:58:03 rivoplus volumio[3177]: info: Executing endpoint tc_getconfig May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus sudo[7956]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: MCU Signalled Headphone Mode Disabled May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 17 11:58:03 rivoplus volumio[3177]: info: Reporting Headphone State: false May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:58:03 rivoplus volumio[3177]: info: Starting Shairport Sync May 17 11:58:03 rivoplus sudo[7968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 17 11:58:03 rivoplus sudo[7968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 17 11:58:03 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 17 11:58:03 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:58:03 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 17 11:58:03 rivoplus sudo[7968]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus volumio[3177]: info: Shairport-Sync Started May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 11:58:03 rivoplus volumio[3177]: info: Not Reporting Auto name since its the default one May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 17 11:58:03 rivoplus volumio[3177]: No protocol specified May 17 11:58:03 rivoplus volumio[3177]: xcb_connection_has_error() returned true May 17 11:58:03 rivoplus sudo[7980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 17 11:58:03 rivoplus sudo[7980]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus sudo[7980]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus sudo[7982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 17 11:58:03 rivoplus sudo[7982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:03 rivoplus systemd[1]: Stopping RAAT DAEMON... May 17 11:58:03 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 17 11:58:03 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 17 11:58:03 rivoplus systemd[1]: Stopped RAAT DAEMON. May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:03 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 17 11:58:03 rivoplus systemd[1]: Started RAAT DAEMON. May 17 11:58:03 rivoplus sudo[7982]: pam_unix(sudo:session): session closed for user root May 17 11:58:03 rivoplus volumio[3177]: info: Raat Daemon started successfully May 17 11:58:03 rivoplus volumio[3177]: info: Executing endpoint restartRAATSocket May 17 11:58:03 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 17 11:58:03 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:03 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 17 11:58:04 rivoplus volumio[3177]: info: MRS: Getting audio outputs on start May 17 11:58:04 rivoplus volumio[3177]: info: MRS: Requesting all other devices output May 17 11:58:04 rivoplus mpd[7959]: May 17 11:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 17 11:58:04 rivoplus systemd[1]: Started Music Player Daemon. May 17 11:58:04 rivoplus sudo[7938]: pam_unix(sudo:session): session closed for user root May 17 11:58:04 rivoplus volumio[3177]: error: updateQueue error: null May 17 11:58:05 rivoplus volumio[3177]: info: TidalConnect service started! May 17 11:58:06 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:58:06 rivoplus volumio[3177]: info: TidalConnect service stoped! May 17 11:58:06 rivoplus volumio[3177]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 17 11:58:06 rivoplus volumio[3177]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 17 11:58:06 rivoplus sudo[8023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 17 11:58:06 rivoplus sudo[8023]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:06 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 17 11:58:06 rivoplus sudo[8023]: pam_unix(sudo:session): session closed for user root May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:58:06 rivoplus volumio[3177]: info: RAAT: Requesting Headphone Status May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 17 11:58:06 rivoplus volumio[3177]: info: MCU Signalled Headphone Mode Disabled May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 17 11:58:06 rivoplus volumio[3177]: info: Reporting Headphone State: false May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 11:58:06 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:58:06 rivoplus volumio[3177]: info: Executing endpoint tc_getconfig May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 17 11:58:06 rivoplus vtcs[8025]: STARTING TidalConnect services, version: 1.4.0.34 May 17 11:58:06 rivoplus vtcs[8025]: STARTED TidalConnect services. May 17 11:58:06 rivoplus volumio[3177]: info: Executing endpoint tc_connect May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 17 11:58:06 rivoplus volumio[3177]: info: Connecting to TidalConnect May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::servicePushState May 17 11:58:06 rivoplus volumio[3177]: info: CoreStateMachine::pushState May 17 11:58:06 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::volumioPushState May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:58:06 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:06 rivoplus volumio[3177]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::servicePushState May 17 11:58:06 rivoplus volumio[3177]: info: CoreStateMachine::pushState May 17 11:58:06 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:06 rivoplus volumio[3177]: info: CoreCommandRouter::volumioPushState May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output update for this device May 17 11:58:06 rivoplus volumio[3177]: info: MRS: Pushing multiroomSync output May 17 11:58:06 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 May 17 11:58:06 rivoplus volumio[3177]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 17 11:58:06 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:58:06 rivoplus volumio[3177]: info: Updating RAAT Signal Path May 17 11:58:08 rivoplus volumio[3177]: info: Executing endpoint metavolumio May 17 11:58:08 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 11:58:09 rivoplus volumio[3177]: info: TidalConnect service started! May 17 11:58:09 rivoplus volumio[3177]: info: Disabling MyMusic plugin upnp May 17 11:58:10 rivoplus sudo[8045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 17 11:58:10 rivoplus sudo[8045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 11:58:10 rivoplus volumio[3177]: error: Upnp client error: Error: This socket has been ended by the other party May 17 11:58:10 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... May 17 11:58:10 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running May 17 11:58:10 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 11:58:12 rivoplus volumio[3177]: info: Enabling MyMusic plugin upnp May 17 11:58:12 rivoplus volumio[3177]: info: Enabling plugin upnp May 17 11:58:12 rivoplus volumio[3177]: info: Loading plugin "upnp"... May 17 11:58:12 rivoplus volumio[3177]: info: [1747450692447] Starting Upmpd Daemon May 17 11:58:12 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 17 11:58:12 rivoplus volumio[3177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 11:58:12 rivoplus volumio[3177]: Error: listen EADDRINUSE: address already in use :::6599 May 17 11:58:12 rivoplus volumio[3177]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 17 11:58:12 rivoplus volumio[3177]: at listenInCluster (net.js:1379:12) May 17 11:58:12 rivoplus volumio[3177]: at Server.listen (net.js:1465:7) May 17 11:58:12 rivoplus volumio[3177]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 17 11:58:12 rivoplus volumio[3177]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 17 11:58:12 rivoplus volumio[3177]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 17 11:58:12 rivoplus volumio[3177]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 17 11:58:12 rivoplus volumio[3177]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 17 11:58:12 rivoplus volumio[3177]: code: 'EADDRINUSE', May 17 11:58:12 rivoplus volumio[3177]: errno: -98, May 17 11:58:12 rivoplus volumio[3177]: syscall: 'listen', May 17 11:58:12 rivoplus volumio[3177]: address: '::', May 17 11:58:12 rivoplus volumio[3177]: port: 6599 May 17 11:58:12 rivoplus volumio[3177]: } May 17 11:58:12 rivoplus volumio[3177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 11:58:13 rivoplus sudo[8057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 11:57 May 17 11:58:13 rivoplus sudo[8057]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:29:52 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c26baf739f7d1f3fe648e6cf51b2c348"