-- Logs begin at Sat 2024-11-09 00:04:03 +08, end at Mon 2024-12-02 21:00:38 +08. -- Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 20:59:00 rivo volumio[3157]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 02 20:59:00 rivo volumio[3157]: info: Received Get System Version Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 20:59:00 rivo volumio[3157]: info: Received Get System Info Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:00 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:00 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:00 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:06 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:06 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:06 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:32 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 02 20:59:32 rivo volumio[3157]: info: MyVolumio login type: Token Dec 02 20:59:32 rivo volumio[3157]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 02 20:59:33 rivo volumio[3157]: info: MyVolumio token set successfully Dec 02 20:59:33 rivo volumio[3157]: info: MYVOLUMIO: Adding device Dec 02 20:59:33 rivo volumio[3157]: info: MYVOLUMIO: Evaluating Server Dec 02 20:59:34 rivo volumio[3157]: info: MyVolumio status changed Dec 02 20:59:34 rivo volumio[3157]: info: Streaming services startup Dec 02 20:59:34 rivo volumio[3157]: info: Starting Streaming Daemon Dec 02 20:59:34 rivo volumio[3157]: info: Removing browser output: myVolumio user plan is not superstar Dec 02 20:59:34 rivo volumio[3157]: info: Removing audio output: Dec 02 20:59:34 rivo volumio[3157]: info: Stoppping Tunnel 1 Dec 02 20:59:34 rivo sudo[7354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 20:59:34 rivo sudo[7354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 20:59:34 rivo sudo[7354]: pam_unix(sudo:session): session closed for user root Dec 02 20:59:34 rivo volumio[3157]: error: Cannot start Volumio Streaming Daemon Dec 02 20:59:34 rivo volumio[3157]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 20:59:34 rivo volumio[3157]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 20:59:34 rivo sudo[7357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 02 20:59:34 rivo sudo[7357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 20:59:34 rivo volumio[3157]: info: Setting Geolocation for MyVolumio to as1 Dec 02 20:59:34 rivo sudo[7357]: pam_unix(sudo:session): session closed for user root Dec 02 20:59:34 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:34 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:34 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:34 rivo volumio[3157]: info: Remote SSH Stopped Dec 02 20:59:34 rivo volumio[3157]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 02 20:59:35 rivo volumio[3157]: info: Updating MyVolumio device info Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 20:59:35 rivo volumio[3157]: info: Received Get System Info Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:35 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:35 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:35 rivo volumio[3157]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 02 20:59:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:36 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:36 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:36 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:38 rivo volumio[3157]: info: MYVOLUMIO: Adding device Dec 02 20:59:38 rivo volumio[3157]: info: MYVOLUMIO: Evaluating Server Dec 02 20:59:39 rivo sudo[7396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 20:59:39 rivo sudo[7396]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 20:59:39 rivo sudo[7396]: pam_unix(sudo:session): session closed for user root Dec 02 20:59:39 rivo sudo[7399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 20:59:39 rivo sudo[7399]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 20:59:39 rivo volumio[3157]: info: Setting Geolocation for MyVolumio to as1 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:39 rivo sudo[7399]: pam_unix(sudo:session): session closed for user root Dec 02 20:59:39 rivo volumio[3157]: verbose: New Socket.io Connection to 192.168.86.30 from 192.168.86.250 UA: Mozilla/5.0 (iPad; CPU OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 02 20:59:39 rivo volumio[3157]: info: Received Get System Info Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:39 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 20:59:39 rivo volumio[3157]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 02 20:59:39 rivo volumio[3157]: info: Received Get System Info Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:39 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: Listing playlists Dec 02 20:59:39 rivo volumio[3157]: info: Received Get System Info Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 20:59:39 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 20:59:39 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 20:59:39 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 20:59:40 rivo volumio[3157]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 02 20:59:40 rivo volumio[3157]: info: Updating MyVolumio device info Dec 02 20:59:40 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:40 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:40 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 20:59:41 rivo volumio[3157]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 02 20:59:49 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 20:59:53 rivo volumio[3157]: info: CoreCommandRouter::volumioGetQueue Dec 02 20:59:53 rivo volumio[3157]: info: CoreStateMachine::getQueue Dec 02 20:59:53 rivo volumio[3157]: info: CorePlayQueue::getQueue Dec 02 21:00:03 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:00:03 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 02 21:00:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:00:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:00:06 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 21:00:06 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 21:00:06 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 21:00:06 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:00:12 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 02 21:00:13 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 02 21:00:34 rivo volumio[3157]: info: Enabling plugin fusiondsp Dec 02 21:00:34 rivo volumio[3157]: info: Loading plugin "fusiondsp"... Dec 02 21:00:35 rivo volumio[3157]: info: Applying required configuration parameters for plugin fusiondsp Dec 02 21:00:35 rivo volumio[3157]: info: Preparing to generate the ALSA configuration file Dec 02 21:00:35 rivo volumio[3157]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 02 21:00:35 rivo volumio[3157]: info: Reading ALSA contributions from plugins. Dec 02 21:00:35 rivo volumio[3157]: info: Asound.conf file unchanged, so no further update is needed Dec 02 21:00:35 rivo volumio[3157]: info: Output device has changed, restarting MPD Dec 02 21:00:35 rivo sudo[7551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 21:00:35 rivo sudo[7551]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:35 rivo volumio[3157]: info: Output device has changed, restarting Shairport Sync Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:00:35 rivo sudo[7551]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:35 rivo sudo[7554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 21:00:35 rivo sudo[7554]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:35 rivo systemd[1]: Stopping Music Player Daemon... Dec 02 21:00:35 rivo volumio[3157]: No protocol specified Dec 02 21:00:35 rivo volumio[3157]: xcb_connection_has_error() returned true Dec 02 21:00:35 rivo volumio[3157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 21:00:35 rivo volumio[3157]: No protocol specified Dec 02 21:00:35 rivo volumio[3157]: xcb_connection_has_error() returned true Dec 02 21:00:35 rivo volumio[3157]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo systemd[1]: mpd.service: Succeeded. Dec 02 21:00:35 rivo systemd[1]: Stopped Music Player Daemon. Dec 02 21:00:35 rivo systemd[1]: Starting Music Player Daemon... Dec 02 21:00:35 rivo volumio[3157]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo vtcs[3852]: [2024-12-02 21:00:35.547] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Dec 02 21:00:35 rivo vtcs[3852]: [2024-12-02 21:00:35.548] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 02 21:00:35 rivo vtcs[3852]: [2024-12-02 21:00:35.549] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Dec 02 21:00:35 rivo volumio[3157]: info: PLUGIN START: fusiondsp Dec 02 21:00:35 rivo kernel: aml_spdif_open Dec 02 21:00:35 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Dec 02 21:00:35 rivo kernel: spdif_a keep clk continuous Dec 02 21:00:35 rivo kernel: aml_spdif_close Dec 02 21:00:35 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Dec 02 21:00:35 rivo volumio[3157]: info: Loading i18n strings for locale en Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 02 21:00:35 rivo sudo[7570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: FusionDsp - mixtype--------------------- None Dec 02 21:00:35 rivo sudo[7570]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:35 rivo volumio[3157]: info: Preparing to generate the ALSA configuration file Dec 02 21:00:35 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Dec 02 21:00:35 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 02 21:00:35 rivo volumio[3157]: info: Done. Dec 02 21:00:35 rivo systemd[1]: vtcs.service: Succeeded. Dec 02 21:00:35 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Dec 02 21:00:35 rivo sudo[7570]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:35 rivo volumio[3157]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 02 21:00:35 rivo volumio[3157]: info: Reading ALSA contributions from plugins. Dec 02 21:00:35 rivo sudo[7560]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 21:00:35 rivo sudo[7560]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:35 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:35 rivo sudo[7560]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:35 rivo sudo[7575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 02 21:00:35 rivo sudo[7575]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:35 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 21:00:35 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:35 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:35 rivo sudo[7575]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:35 rivo volumio[3157]: info: MPD Permissions set Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 02 21:00:35 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 21:00:36 rivo volumio[3157]: info: Not Reporting Auto name since its the default one Dec 02 21:00:36 rivo volumio[3157]: info: Starting Shairport Sync Dec 02 21:00:36 rivo sudo[7592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 21:00:36 rivo sudo[7592]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:36 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 21:00:36 rivo systemd[1]: shairport-sync.service: Succeeded. Dec 02 21:00:36 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 21:00:36 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 21:00:36 rivo sudo[7592]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 21:00:37 rivo volumio[3157]: info: Shairport-Sync Started Dec 02 21:00:37 rivo volumio[3157]: info: Asound.conf file unchanged, so no further update is needed Dec 02 21:00:37 rivo volumio[3157]: info: Output device has changed, restarting MPD Dec 02 21:00:37 rivo sudo[7600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 21:00:37 rivo sudo[7600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo volumio[3157]: info: Output device has changed, restarting Shairport Sync Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:00:37 rivo sudo[7600]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo sudo[7603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 21:00:37 rivo sudo[7603]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 02 21:00:37 rivo systemd[1]: mpd.service: Succeeded. Dec 02 21:00:37 rivo systemd[1]: Stopped Music Player Daemon. Dec 02 21:00:37 rivo systemd[1]: Starting Music Player Daemon... Dec 02 21:00:37 rivo volumio[3157]: No protocol specified Dec 02 21:00:37 rivo volumio[3157]: xcb_connection_has_error() returned true Dec 02 21:00:37 rivo volumio[3157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 21:00:37 rivo volumio[3157]: No protocol specified Dec 02 21:00:37 rivo volumio[3157]: xcb_connection_has_error() returned true Dec 02 21:00:37 rivo volumio[3157]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo kernel: aml_spdif_open Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo kernel: spdif_a keep clk continuous Dec 02 21:00:37 rivo kernel: aml_spdif_close Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo sudo[7614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 02 21:00:37 rivo sudo[7614]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:00:37 rivo volumio[3157]: info: Discovery: Getting this device information Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::volumioGetState Dec 02 21:00:37 rivo volumio[3157]: info: CorePlayQueue::getTrack 0 Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 02 21:00:37 rivo sudo[7617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 02 21:00:37 rivo sudo[7617]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo sudo[7609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 21:00:37 rivo sudo[7609]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo sudo[7614]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo sudo[7609]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo sudo[7617]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo volumio[3157]: No protocol specified Dec 02 21:00:37 rivo volumio[3157]: xcb_connection_has_error() returned true Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 21:00:37 rivo volumio[3157]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 21:00:37 rivo sudo[7626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 02 21:00:37 rivo sudo[7626]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo volumio[3157]: info: MPD Permissions set Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo sudo[7626]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo sudo[7628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 02 21:00:37 rivo sudo[7628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 02 21:00:37 rivo systemd[1]: Stopping RAAT DAEMON... Dec 02 21:00:37 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 02 21:00:37 rivo systemd[1]: raat-daemon.service: Succeeded. Dec 02 21:00:37 rivo systemd[1]: Stopped RAAT DAEMON. Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo systemd[1]: Started RAAT DAEMON. Dec 02 21:00:37 rivo sudo[7628]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:37 rivo kernel: aml_spdif_open Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo kernel: spdif_a keep clk continuous Dec 02 21:00:37 rivo kernel: aml_spdif_close Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo kernel: aml_spdif_open Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo kernel: spdif_a keep clk continuous Dec 02 21:00:37 rivo kernel: aml_spdif_close Dec 02 21:00:37 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Dec 02 21:00:37 rivo volumio[3157]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 02 21:00:37 rivo volumio[3157]: info: camilladsp service started and running in background, instance 1 Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 02 21:00:37 rivo volumio[3157]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 02 21:00:37 rivo volumio[3157]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 02 21:00:37 rivo volumio[3157]: info: FusionDsp loaded Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:00:37 rivo sudo[7659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 02 21:00:37 rivo sudo[7659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:00:37 rivo volumio[3157]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 02 21:00:37 rivo volumio[3157]: info: Adding Signal Path Element [object Object] Dec 02 21:00:37 rivo volumio[3157]: info: Adding fusiondspeq DSP Signal Path Element Dec 02 21:00:37 rivo volumio[3157]: info: Additional DSP elements updated Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 21:00:37 rivo volumio[3157]: info: Updating RAAT Signal Path Dec 02 21:00:37 rivo volumio[3157]: info: FusionDsp - ---- installed callbackRead Dec 02 21:00:37 rivo volumio[3157]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:00:37 rivo systemd[1]: Started FusionDsp Daemon. Dec 02 21:00:37 rivo volumio[3157]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 02 21:00:37 rivo volumio[3157]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 02 21:00:37 rivo volumio[3157]: at onErrorNT (internal/child_process.js:472:16) Dec 02 21:00:37 rivo volumio[3157]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 02 21:00:37 rivo volumio[3157]: at runNextTicks (internal/process/task_queues.js:64:3) Dec 02 21:00:37 rivo volumio[3157]: at listOnTimeout (internal/timers.js:526:9) Dec 02 21:00:37 rivo volumio[3157]: at processTimers (internal/timers.js:500:7) { Dec 02 21:00:37 rivo volumio[3157]: errno: -2, Dec 02 21:00:37 rivo volumio[3157]: code: 'ENOENT', Dec 02 21:00:37 rivo volumio[3157]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 02 21:00:37 rivo volumio[3157]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 02 21:00:37 rivo volumio[3157]: spawnargs: [ Dec 02 21:00:37 rivo volumio[3157]: '-p', Dec 02 21:00:37 rivo volumio[3157]: 9876, Dec 02 21:00:37 rivo volumio[3157]: '-o', Dec 02 21:00:37 rivo volumio[3157]: '/tmp/camilladsp.log', Dec 02 21:00:37 rivo volumio[3157]: '-l', Dec 02 21:00:37 rivo volumio[3157]: 'warn', Dec 02 21:00:37 rivo volumio[3157]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 02 21:00:37 rivo volumio[3157]: ] Dec 02 21:00:37 rivo volumio[3157]: } Dec 02 21:00:37 rivo volumio[3157]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:00:37 rivo sudo[7659]: pam_unix(sudo:session): session closed for user root Dec 02 21:00:38 rivo sudo[7690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 20:59 Dec 02 21:00:38 rivo sudo[7690]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"