-- Logs begin at Sat 2024-10-26 23:07:34 HKT, end at Sat 2024-11-02 08:54:31 HKT. -- Nov 02 08:53:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 08:53:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 08:53:18 rivo volumio[3370]: info: Discovery: Getting this device information Nov 02 08:53:18 rivo volumio[3370]: info: CoreCommandRouter::volumioGetState Nov 02 08:53:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: Retrieving Cloud Streaming UI Nov 02 08:53:48 rivo volumio[3370]: info: Getting Tidal Cloud Configuration Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: Getting Qobuz Cloud Configuration Nov 02 08:53:48 rivo volumio[3370]: info: Asking plugin for UI Config Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: Getting Spotify Cloud Configuration Nov 02 08:53:48 rivo volumio[3370]: info: Asking plugin for UI Config Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: Saving Spotify Acccount Nov 02 08:53:48 rivo volumio[3370]: info: Got it Nov 02 08:53:48 rivo volumio[3370]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 08:53:48 rivo volumio[3370]: info: Got Tidal Cloud Configuration Nov 02 08:53:48 rivo volumio[3370]: info: Got it Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 08:53:48 rivo volumio[3370]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 08:53:49 rivo volumio[3370]: info: Discovery: Getting this device information Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::volumioGetState Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 08:53:49 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 08:53:53 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:53:57 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:01 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:05 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:09 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:09 rivo volumio[3370]: info: Disabling MyMusic plugin bluetooth Nov 02 08:54:09 rivo sudo[15762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Nov 02 08:54:09 rivo sudo[15762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:09 rivo systemd[1]: Stopping Volumio Bluetooth Module... Nov 02 08:54:09 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:09 rivo volumiobt[15764]: Killing pulse Nov 02 08:54:09 rivo volumiobt[15764]: Killing BT Pair agent Nov 02 08:54:09 rivo bluetoothd[3195]: Endpoint unregistered: sender=:1.39 path=/MediaEndpoint/A2DPSource Nov 02 08:54:09 rivo bluetoothd[3195]: Endpoint unregistered: sender=:1.39 path=/MediaEndpoint/A2DPSink Nov 02 08:54:09 rivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Nov 02 08:54:09 rivo systemd[1]: volumiobt.service: Succeeded. Nov 02 08:54:09 rivo systemd[1]: Stopped Volumio Bluetooth Module. Nov 02 08:54:09 rivo sudo[15762]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:09 rivo volumio[3370]: info: Volumio BT Module successfully stopped Nov 02 08:54:09 rivo volumio[3370]: info: Disabling plugin bluetooth Nov 02 08:54:09 rivo volumio[3370]: info: Done. Nov 02 08:54:12 rivo volumio[3370]: info: Enabling MyMusic plugin bluetooth Nov 02 08:54:12 rivo volumio[3370]: info: Enabling plugin bluetooth Nov 02 08:54:12 rivo volumio[3370]: info: Loading plugin "bluetooth"... Nov 02 08:54:12 rivo volumio[3370]: info: [1730508852988] Starting BluetoothController Nov 02 08:54:12 rivo volumio[3370]: info: PLUGIN START: bluetooth Nov 02 08:54:12 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 08:54:13 rivo volumio[3370]: info: Done. Nov 02 08:54:13 rivo sudo[15776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Nov 02 08:54:13 rivo sudo[15776]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:13 rivo sudo[15776]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:13 rivo volumio[3370]: info: Bluetooth name changed to Rivo Nov 02 08:54:13 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:16 rivo sudo[15805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 02 08:54:16 rivo sudo[15805]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:16 rivo systemd[1]: Started Volumio Bluetooth Module. Nov 02 08:54:16 rivo sudo[15805]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:16 rivo volumio[3370]: info: Volumio BT Module successfully started Nov 02 08:54:16 rivo sudo[15813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 02 08:54:16 rivo sudo[15813]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:16 rivo volumiobt[15807]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 02 08:54:16 rivo sudo[15813]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:17 rivo sudo[15823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 02 08:54:17 rivo sudo[15823]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:17 rivo volumiobt[15807]: pulseaudio: no process found Nov 02 08:54:17 rivo sudo[15823]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:17 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:17 rivo pulseaudio[15827]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 02 08:54:17 rivo pulseaudio[15827]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 02 08:54:17 rivo volumiobt[15807]: Applying permissions Nov 02 08:54:17 rivo volumiobt[15807]: Setting BT discoverable and pairable Nov 02 08:54:17 rivo bluetoothd[3195]: Endpoint registered: sender=:1.68 path=/MediaEndpoint/A2DPSource Nov 02 08:54:17 rivo bluetoothd[3195]: Endpoint registered: sender=:1.68 path=/MediaEndpoint/A2DPSink Nov 02 08:54:17 rivo pulseaudio[15827]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 02 08:54:17 rivo volumiobt[15807]: Agent registered Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# -e power on Nov 02 08:54:17 rivo volumiobt[15807]: Invalid command in menu main: -e Nov 02 08:54:17 rivo volumiobt[15807]: Nov 02 08:54:17 rivo volumiobt[15807]: Use "help" for a list of available commands in a menu. Nov 02 08:54:17 rivo volumiobt[15807]: Use "menu " if you want to enter any submenu. Nov 02 08:54:17 rivo volumiobt[15807]: Use "back" if you want to return to menu main. Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# agent on Nov 02 08:54:17 rivo volumiobt[15807]: Agent is already registered Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# discoverable on Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# pairable on Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# agent NoInputNoOutput Nov 02 08:54:17 rivo volumiobt[15807]: Agent is already registered Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# default-agent Nov 02 08:54:17 rivo volumiobt[15807]: [bluetooth]# quit Nov 02 08:54:17 rivo volumiobt[15807]: [59B blob data] Nov 02 08:54:17 rivo volumio[3370]: info: Disabling MyMusic plugin multiroom Nov 02 08:54:17 rivo volumio[3370]: info: Disabling plugin multiroom Nov 02 08:54:17 rivo volumio[3370]: info: Preparing to generate the ALSA configuration file Nov 02 08:54:17 rivo volumio[3370]: info: Asound.conf file written Nov 02 08:54:17 rivo sudo[15835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 02 08:54:17 rivo sudo[15835]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:17 rivo sudo[15835]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:17 rivo kernel: Fine tdm clk setting range (0~2000000), 11289593 Nov 02 08:54:17 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Nov 02 08:54:17 rivo kernel: out of value, fixed it Nov 02 08:54:17 rivo kernel: id=0 set inskew=0 Nov 02 08:54:17 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:17 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Nov 02 08:54:17 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Nov 02 08:54:17 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Nov 02 08:54:17 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Nov 02 08:54:17 rivo volumio[3370]: info: Output device has changed, restarting MPD Nov 02 08:54:18 rivo sudo[15840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 08:54:18 rivo sudo[15840]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo volumio[3370]: info: Output device has changed, restarting Shairport Sync Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 08:54:18 rivo sudo[15840]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo sudo[15843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 08:54:18 rivo sudo[15843]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo systemd[1]: Stopping Music Player Daemon... Nov 02 08:54:18 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:18 rivo volumio[3370]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 08:54:18 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:18 rivo volumio[3370]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo vtcs[3752]: [2024-11-02 08:54:18.133] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 02 08:54:18 rivo vtcs[3752]: [2024-11-02 08:54:18.133] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 02 08:54:18 rivo vtcs[3752]: [2024-11-02 08:54:18.133] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Nov 02 08:54:18 rivo volumio[3370]: info: Done. Nov 02 08:54:18 rivo sudo[15851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 08:54:18 rivo sudo[15851]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo systemd[1]: mpd.service: Succeeded. Nov 02 08:54:18 rivo systemd[1]: Stopped Music Player Daemon. Nov 02 08:54:18 rivo systemd[1]: Starting Music Player Daemon... Nov 02 08:54:18 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo sudo[15856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 08:54:18 rivo sudo[15856]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo systemd[1]: vtcs.service: Succeeded. Nov 02 08:54:18 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Nov 02 08:54:18 rivo volumio[3370]: info: MPD Permissions set Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo sudo[15851]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 08:54:18 rivo volumio[3370]: info: Discovery: Getting this device information Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::volumioGetState Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 08:54:18 rivo sudo[15856]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 08:54:18 rivo sudo[15855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 08:54:18 rivo sudo[15855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: Starting Shairport Sync Nov 02 08:54:18 rivo sudo[15855]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo sudo[15870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 08:54:18 rivo sudo[15870]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 08:54:18 rivo systemd[1]: shairport-sync.service: Succeeded. Nov 02 08:54:18 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 08:54:18 rivo sudo[15870]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo volumio[3370]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 08:54:18 rivo volumio[3370]: info: Shairport-Sync Started Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:54:18 rivo volumio[3370]: info: Not Reporting Auto name since its the default one Nov 02 08:54:18 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 02 08:54:18 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:18 rivo sudo[15884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 02 08:54:18 rivo sudo[15884]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo sudo[15884]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo sudo[15886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 02 08:54:18 rivo sudo[15886]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:18 rivo systemd[1]: Stopping RAAT DAEMON... Nov 02 08:54:18 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 02 08:54:18 rivo systemd[1]: raat-daemon.service: Succeeded. Nov 02 08:54:18 rivo systemd[1]: Stopped RAAT DAEMON. Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo systemd[1]: Started RAAT DAEMON. Nov 02 08:54:18 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:18 rivo sudo[15886]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:18 rivo volumio[3370]: info: Raat Daemon started successfully Nov 02 08:54:19 rivo volumio[3370]: info: Executing endpoint restartRAATSocket Nov 02 08:54:19 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 02 08:54:19 rivo volumio[3370]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 02 08:54:20 rivo mpd[15869]: Nov 02 08:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 08:54:20 rivo systemd[1]: Started Music Player Daemon. Nov 02 08:54:20 rivo sudo[15843]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:20 rivo volumio[3370]: error: updateQueue error: null Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:21 rivo volumio[3370]: info: TidalConnect service stoped! Nov 02 08:54:21 rivo volumio[3370]: info: TidalConnect service stoped! Nov 02 08:54:21 rivo volumio[3370]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 08:54:21 rivo volumio[3370]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 08:54:21 rivo sudo[15913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 08:54:21 rivo sudo[15913]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:21 rivo systemd[1]: Started Volumio Tidal Connect Service. Nov 02 08:54:21 rivo sudo[15913]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:21 rivo volumio[3370]: info: Executing endpoint tc_getconfig Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 08:54:21 rivo vtcs[15915]: STARTING TidalConnect services, version: 1.4.0.34 Nov 02 08:54:21 rivo vtcs[15915]: STARTED TidalConnect services. Nov 02 08:54:21 rivo volumio[3370]: info: Executing endpoint tc_connect Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 08:54:21 rivo volumio[3370]: info: Connecting to TidalConnect Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::servicePushState Nov 02 08:54:21 rivo volumio[3370]: info: CoreStateMachine::pushState Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::volumioPushState Nov 02 08:54:21 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:21 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:21 rivo volumio[3370]: info: CorePlayQueue::getTrack 2 Nov 02 08:54:21 rivo volumio[3370]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::servicePushState Nov 02 08:54:21 rivo volumio[3370]: info: CoreStateMachine::pushState Nov 02 08:54:21 rivo volumio[3370]: info: CoreCommandRouter::volumioPushState Nov 02 08:54:21 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:21 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:21 rivo volumio[3370]: info: CorePlayQueue::getTrack 2 Nov 02 08:54:21 rivo volumio[3370]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 02 08:54:22 rivo volumio[3370]: info: Enabling MyMusic plugin multiroom Nov 02 08:54:22 rivo volumio[3370]: info: Enabling plugin multiroom Nov 02 08:54:22 rivo volumio[3370]: info: Loading plugin "multiroom"... Nov 02 08:54:22 rivo volumio[3370]: info: Applying required configuration parameters for plugin multiroom Nov 02 08:54:22 rivo sudo[15939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 02 08:54:22 rivo sudo[15939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:22 rivo sudo[15939]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:22 rivo volumio[3370]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Nov 02 08:54:23 rivo volumio[3370]: info: MRS: MultiRoom plugin initialized Nov 02 08:54:23 rivo volumio[3370]: info: MRS: STOPPING SNAPCLIENT Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Snap server stop Nov 02 08:54:23 rivo sudo[15955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 02 08:54:23 rivo sudo[15955]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo volumio[3370]: info: MRS: STOPPING volumioStreaming Nov 02 08:54:23 rivo sudo[15958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 02 08:54:23 rivo sudo[15958]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo sudo[15955]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo sudo[15961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 02 08:54:23 rivo sudo[15961]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo sudo[15958]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo volumio[3370]: info: Preparing to generate the ALSA configuration file Nov 02 08:54:23 rivo sudo[15961]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo sudo[15965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 02 08:54:23 rivo sudo[15965]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo sudo[15965]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo volumio[3370]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 02 08:54:23 rivo volumio[3370]: info: Reading ALSA contributions from plugins. Nov 02 08:54:23 rivo volumio[3370]: info: MRS: volumioStreaming STOPPED Nov 02 08:54:23 rivo volumio[3370]: info: MRS: SNAPSERVER STOPPED Nov 02 08:54:23 rivo volumio[3370]: info: MRS: SNAPCLIENT STOPPED Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Removed streaming files Nov 02 08:54:23 rivo volumio[3370]: info: Asound.conf file written Nov 02 08:54:23 rivo sudo[15968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 02 08:54:23 rivo sudo[15968]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo sudo[15968]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo kernel: Fine tdm clk setting range (0~2000000), 11289593 Nov 02 08:54:23 rivo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Nov 02 08:54:23 rivo kernel: out of value, fixed it Nov 02 08:54:23 rivo kernel: id=0 set inskew=0 Nov 02 08:54:23 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:23 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Nov 02 08:54:23 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Nov 02 08:54:23 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Nov 02 08:54:23 rivo volumio[3370]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Nov 02 08:54:23 rivo volumio[3370]: info: Output device has changed, restarting MPD Nov 02 08:54:23 rivo sudo[15973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 08:54:23 rivo sudo[15973]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo volumio[3370]: info: Output device has changed, restarting Shairport Sync Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 08:54:23 rivo sudo[15973]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo sudo[15976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 08:54:23 rivo sudo[15976]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo systemd[1]: Stopping Music Player Daemon... Nov 02 08:54:23 rivo systemd[1]: mpd.service: Succeeded. Nov 02 08:54:23 rivo systemd[1]: Stopped Music Player Daemon. Nov 02 08:54:23 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:23 rivo volumio[3370]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 08:54:23 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo systemd[1]: Starting Music Player Daemon... Nov 02 08:54:23 rivo volumio[3370]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo vtcs[15915]: [2024-11-02 08:54:23.542] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 02 08:54:23 rivo vtcs[15915]: [2024-11-02 08:54:23.542] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo sudo[15986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 08:54:23 rivo sudo[15986]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Nov 02 08:54:23 rivo volumio[3370]: info: PLUGIN START: multiroom Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Pushing multiroomSync output for this device Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:23 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 02 08:54:23 rivo systemd[1]: vtcs.service: Succeeded. Nov 02 08:54:23 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Nov 02 08:54:23 rivo volumio[3370]: info: Adding audio output: Nov 02 08:54:23 rivo volumio[3370]: error: Can't add: Nov 02 08:54:23 rivo volumio[3370]: info: Adding audio output: Nov 02 08:54:23 rivo volumio[3370]: error: Can't add: Nov 02 08:54:23 rivo sudo[15986]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo sudo[15982]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 08:54:23 rivo sudo[15982]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo volumio[3370]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo sudo[15982]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo sudo[16000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 02 08:54:23 rivo sudo[16000]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:23 rivo volumio[3370]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 08:54:23 rivo volumio[3370]: info: MPD Permissions set Nov 02 08:54:23 rivo sudo[16000]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 02 08:54:23 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:23 rivo volumio[3370]: info: Done. Nov 02 08:54:23 rivo volumio[3370]: info: Starting Shairport Sync Nov 02 08:54:23 rivo sudo[16010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 08:54:23 rivo sudo[16010]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:24 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 08:54:24 rivo systemd[1]: shairport-sync.service: Succeeded. Nov 02 08:54:24 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:24 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 08:54:24 rivo sudo[16010]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:24 rivo volumio[3370]: info: Shairport-Sync Started Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 08:54:24 rivo volumio[3370]: info: Not Reporting Auto name since its the default one Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 02 08:54:24 rivo volumio[3370]: xcb_connection_has_error() returned true Nov 02 08:54:24 rivo sudo[16023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 02 08:54:24 rivo sudo[16023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:24 rivo sudo[16023]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:24 rivo sudo[16025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 02 08:54:24 rivo sudo[16025]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:24 rivo systemd[1]: Stopping RAAT DAEMON... Nov 02 08:54:24 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 02 08:54:24 rivo systemd[1]: raat-daemon.service: Succeeded. Nov 02 08:54:24 rivo systemd[1]: Stopped RAAT DAEMON. Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:24 rivo systemd[1]: Started RAAT DAEMON. Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:24 rivo sudo[16025]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:24 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 08:54:24 rivo volumio[3370]: info: Raat Daemon started successfully Nov 02 08:54:24 rivo volumio[3370]: info: Executing endpoint restartRAATSocket Nov 02 08:54:24 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 02 08:54:24 rivo volumio[3370]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 02 08:54:24 rivo volumio[3370]: info: TidalConnect service started! Nov 02 08:54:25 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:25 rivo volumio[3370]: error: MPD error: Error: This socket has been ended by the other party Nov 02 08:54:25 rivo volumio[3370]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 02 08:54:25 rivo volumio[3370]: error: MPD error: Error: This socket has been ended by the other party Nov 02 08:54:25 rivo volumio[3370]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 02 08:54:25 rivo volumio[3370]: error: MPD error: Error: This socket has been ended by the other party Nov 02 08:54:25 rivo volumio[3370]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 02 08:54:25 rivo mpd[15997]: Nov 02 08:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 08:54:25 rivo systemd[1]: Started Music Player Daemon. Nov 02 08:54:25 rivo sudo[15976]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:25 rivo volumio[3370]: error: updateQueue error: null Nov 02 08:54:26 rivo volumio[3370]: info: TidalConnect service stoped! Nov 02 08:54:26 rivo volumio[3370]: info: TidalConnect service stoped! Nov 02 08:54:26 rivo volumio[3370]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 08:54:26 rivo volumio[3370]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 08:54:26 rivo sudo[16053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 08:54:26 rivo sudo[16053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:27 rivo systemd[1]: Started Volumio Tidal Connect Service. Nov 02 08:54:27 rivo sudo[16053]: pam_unix(sudo:session): session closed for user root Nov 02 08:54:27 rivo volumio[3370]: info: Executing endpoint tc_getconfig Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 08:54:27 rivo vtcs[16055]: STARTING TidalConnect services, version: 1.4.0.34 Nov 02 08:54:27 rivo vtcs[16055]: STARTED TidalConnect services. Nov 02 08:54:27 rivo volumio[3370]: info: Executing endpoint tc_connect Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 08:54:27 rivo volumio[3370]: info: Connecting to TidalConnect Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::servicePushState Nov 02 08:54:27 rivo volumio[3370]: info: CoreStateMachine::pushState Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::volumioPushState Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:27 rivo volumio[3370]: info: CorePlayQueue::getTrack 2 Nov 02 08:54:27 rivo volumio[3370]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::servicePushState Nov 02 08:54:27 rivo volumio[3370]: info: CoreStateMachine::pushState Nov 02 08:54:27 rivo volumio[3370]: info: CoreCommandRouter::volumioPushState Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output update for this device Nov 02 08:54:27 rivo volumio[3370]: info: MRS: Pushing multiroomSync output Nov 02 08:54:27 rivo volumio[3370]: info: CorePlayQueue::getTrack 2 Nov 02 08:54:27 rivo volumio[3370]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 02 08:54:28 rivo volumio[3370]: info: MRS: Getting audio outputs on start Nov 02 08:54:28 rivo volumio[3370]: info: MRS: Requesting all other devices output Nov 02 08:54:29 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 08:54:29 rivo volumio[3370]: info: Disabling MyMusic plugin upnp Nov 02 08:54:29 rivo sudo[16080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 02 08:54:29 rivo sudo[16080]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 08:54:29 rivo volumio[3370]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 08:54:29 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 02 08:54:29 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:29 rivo volumio[3370]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 08:54:30 rivo volumio[3370]: info: TidalConnect service started! Nov 02 08:54:30 rivo volumio[3370]: info: Enabling MyMusic plugin upnp Nov 02 08:54:30 rivo volumio[3370]: info: Enabling plugin upnp Nov 02 08:54:30 rivo volumio[3370]: info: Loading plugin "upnp"... Nov 02 08:54:30 rivo volumio[3370]: info: [1730508870642] Starting Upmpd Daemon Nov 02 08:54:30 rivo volumio[3370]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 08:54:30 rivo volumio[3370]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 08:54:30 rivo volumio[3370]: Error: listen EADDRINUSE: address already in use :::6599 Nov 02 08:54:30 rivo volumio[3370]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Nov 02 08:54:30 rivo volumio[3370]: at listenInCluster (net.js:1379:12) Nov 02 08:54:30 rivo volumio[3370]: at Server.listen (net.js:1465:7) Nov 02 08:54:30 rivo volumio[3370]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 02 08:54:30 rivo volumio[3370]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 02 08:54:30 rivo volumio[3370]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 02 08:54:30 rivo volumio[3370]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 08:54:30 rivo volumio[3370]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Nov 02 08:54:30 rivo volumio[3370]: code: 'EADDRINUSE', Nov 02 08:54:30 rivo volumio[3370]: errno: -98, Nov 02 08:54:30 rivo volumio[3370]: syscall: 'listen', Nov 02 08:54:30 rivo volumio[3370]: address: '::', Nov 02 08:54:30 rivo volumio[3370]: port: 6599 Nov 02 08:54:30 rivo volumio[3370]: } Nov 02 08:54:30 rivo volumio[3370]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 08:54:31 rivo sudo[16109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 08:53 Nov 02 08:54:31 rivo sudo[16109]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"