-- Logs begin at Fri 2025-09-12 12:30:01 BST, end at Fri 2025-09-12 12:32:34 BST. -- Sep 12 12:31:00 rivo volumio[3136]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 15 Sep 12 12:31:00 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:00 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetVisibleSources Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetQueue Sep 12 12:31:00 rivo volumio[3136]: info: CoreStateMachine::getQueue Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getQueue Sep 12 12:31:00 rivo volumio[3136]: info: Listing playlists Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 12 12:31:00 rivo volumio[3136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 12 12:31:00 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:00 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:00 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:00 rivo sudo[4398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 12 12:31:00 rivo sudo[4398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:00 rivo sudo[4398]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:00 rivo sudo[4400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 12 12:31:00 rivo sudo[4400]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:00 rivo volumio[3136]: info: MyVolumio login type: Token Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo sudo[4400]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:00 rivo volumio[3136]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16 Sep 12 12:31:03 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:03 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetVisibleSources Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetQueue Sep 12 12:31:03 rivo volumio[3136]: info: CoreStateMachine::getQueue Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getQueue Sep 12 12:31:03 rivo volumio[3136]: info: Listing playlists Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 12 12:31:03 rivo volumio[3136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 12 12:31:03 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:03 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:03 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:03 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 12 12:31:03 rivo volumio[3136]: info: MyVolumio login type: Token Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:03 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:03 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:03 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:03 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:03 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:03 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:03 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:04 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 12 12:31:04 rivo volumio[3136]: info: MyVolumio login type: Token Sep 12 12:31:04 rivo volumio[3136]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 12 12:31:04 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 12 12:31:04 rivo volumio[3136]: info: MyVolumio login type: Token Sep 12 12:31:05 rivo volumio[3136]: info: MyVolumio token set successfully Sep 12 12:31:05 rivo volumio[3136]: info: MYVOLUMIO: Adding device Sep 12 12:31:05 rivo volumio[3136]: info: MYVOLUMIO: Evaluating Server Sep 12 12:31:06 rivo volumio[3136]: info: MyVolumio status changed Sep 12 12:31:06 rivo volumio[3136]: info: Streaming services startup Sep 12 12:31:06 rivo volumio[3136]: info: Starting Streaming Daemon Sep 12 12:31:06 rivo volumio[3136]: info: Removing browser output: myVolumio user plan is not superstar Sep 12 12:31:06 rivo volumio[3136]: info: Removing audio output: Sep 12 12:31:06 rivo volumio[3136]: info: Stoppping Tunnel 1 Sep 12 12:31:06 rivo sudo[4490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 12 12:31:06 rivo sudo[4490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:06 rivo systemd[1]: Started Music Player Daemon. Sep 12 12:31:06 rivo sudo[4161]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo sudo[4224]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo sudo[3988]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo sudo[4097]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:06 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:06 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:06 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:06 rivo sudo[4490]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo sudo[4496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 12 12:31:06 rivo sudo[4496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:06 rivo volumio[3136]: Upnp client error: Error: This socket has been ended by the other party Sep 12 12:31:06 rivo volumio[3136]: Upnp client error: Error: This socket has been ended by the other party Sep 12 12:31:06 rivo volumio[3136]: Upnp client error: Error: This socket has been ended by the other party Sep 12 12:31:06 rivo volumio[3136]: error: Cannot start Volumio Streaming Daemon Sep 12 12:31:06 rivo volumio[3136]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 12 12:31:06 rivo volumio[3136]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 12 12:31:06 rivo sudo[4496]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:06 rivo volumio[3136]: info: Remote SSH Stopped Sep 12 12:31:06 rivo volumio[3136]: error: MPD error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: MPD error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: MPD error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: The expression evaluated to a falsy value: Sep 12 12:31:06 rivo volumio[3136]: assert.ok(self.idling) Sep 12 12:31:06 rivo volumio[3136]: error: updateQueue error: null Sep 12 12:31:06 rivo volumio[3136]: info: Setting Geolocation for MyVolumio to eu3 Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:06 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:06 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:06 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:06 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:06 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:06 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:06 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:07 rivo volumio[3136]: info: Updating MyVolumio device info Sep 12 12:31:07 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:07 rivo volumio[3136]: info: Successfully Added MyVolumio device Sep 12 12:31:07 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 12 12:31:07 rivo volumio[3136]: info: Successfully Updated MyVolumio device Sep 12 12:31:08 rivo volumio[3136]: error: MyVolumio Plugin failed to start in a timely fashion Sep 12 12:31:08 rivo volumio[3136]: [Metrics] CommandRouter: 58s 183.30ms Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::volumiosetStartupVolume Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:08 rivo volumio[3136]: info: VolumeController:: Setting startup Volume 100 Sep 12 12:31:08 rivo volumio[3136]: info: VolumeController::SetAlsaVolume100 Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::Close All Modals sent Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::Close All Modals sent Sep 12 12:31:08 rivo volumio[3136]: info: CoreStateMachine::pushState Sep 12 12:31:08 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::volumioPushState Sep 12 12:31:08 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:08 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:08 rivo volumio[3136]: info: MRS: Pushing multiroomSync output update for this device Sep 12 12:31:08 rivo volumio[3136]: info: MRS: Pushing multiroomSync output Sep 12 12:31:08 rivo volumio[3136]: info: Updating RAAT Signal Path Sep 12 12:31:08 rivo volumio[3136]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Sep 12 12:31:08 rivo volumio[3136]: xcb_connection_has_error() returned true Sep 12 12:31:08 rivo volumio[3136]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 12 12:31:08 rivo volumio[3136]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 12 12:31:08 rivo volumio[3136]: info: camilladsp stopping service pid 3972... Sep 12 12:31:08 rivo volumio[3136]: info: camilladsp service terminated, instance 1 Sep 12 12:31:08 rivo volumio[3136]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 12 12:31:08 rivo volumio[3136]: info: camilladsp service started and running in background, instance 1 Sep 12 12:31:08 rivo volumio[3136]: info: camilladsp spawned new process with pid 4505, instance 1, run: true Sep 12 12:31:09 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 12 12:31:09 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 12 12:31:09 rivo volumio[3136]: No protocol specified Sep 12 12:31:09 rivo volumio[3136]: xcb_connection_has_error() returned true Sep 12 12:31:09 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 12 12:31:09 rivo volumio[3136]: info: Version has changed, forcing UI Reload Sep 12 12:31:09 rivo volumio[3136]: info: CoreCommandRouter::Reload Ui Sep 12 12:31:10 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:31:10 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 12:31:10 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:10 rivo volumio-remote-updater[2872]: Test mode enabled Sep 12 12:31:10 rivo volumio-remote-updater[2872]: No alpha test mode Sep 12 12:31:10 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 12 12:31:10 rivo volumio[3136]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 12 12:31:10 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 12 12:31:10 rivo sudo[4518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 12 12:31:10 rivo sudo[4518]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:11 rivo sudo[4520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 12 12:31:11 rivo sudo[4518]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:11 rivo sudo[4520]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:11 rivo sudo[4520]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:11 rivo sudo[4524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 12 12:31:11 rivo sudo[4524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:11 rivo sudo[4524]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:11 rivo volumio[3136]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Sep 12 12:31:11 rivo sudo[4531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 12 12:31:11 rivo sudo[4531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:11 rivo sudo[4531]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:11 rivo volumio[3136]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 12 12:31:11 rivo volumio[3136]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::volumioGetVisibleSources Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:11 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::volumioGetQueue Sep 12 12:31:11 rivo volumio[3136]: info: CoreStateMachine::getQueue Sep 12 12:31:11 rivo volumio[3136]: info: CorePlayQueue::getQueue Sep 12 12:31:11 rivo volumio[3136]: info: Listing playlists Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 12 12:31:11 rivo volumio[3136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 12 12:31:11 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:11 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:11 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:11 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:11 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:12 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:12 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetVisibleSources Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 12 12:31:12 rivo volumio[3136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 12 12:31:12 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:12 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: Listing playlists Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:12 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:12 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:12 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 12 12:31:12 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:13 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:13 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:13 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 12 12:31:13 rivo volumio[3136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 12 12:31:13 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:13 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:14 rivo volumio[3136]: info: camilladsp spawned new process with pid 4544, instance 1, run: true Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 12 12:31:15 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:15 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:15 rivo volumio[3136]: info: Executing endpoint metavolumio Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:15 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:15 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:15 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:15 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:15 rivo volumio[3136]: info: BOOT COMPLETED Sep 12 12:31:16 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:16 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 12 12:31:16 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 12 12:31:16 rivo volumio[3136]: No protocol specified Sep 12 12:31:16 rivo volumio[3136]: xcb_connection_has_error() returned true Sep 12 12:31:16 rivo sudo[4575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Sep 12 12:31:16 rivo sudo[4575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:16 rivo sudo[4575]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:16 rivo sudo[4577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 12 12:31:16 rivo sudo[4577]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:16 rivo systemd[1]: Stopping RAAT DAEMON... Sep 12 12:31:16 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 12 12:31:16 rivo systemd[1]: raat-daemon.service: Succeeded. Sep 12 12:31:16 rivo volumio[3136]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 12 12:31:16 rivo systemd[1]: Stopped RAAT DAEMON. Sep 12 12:31:16 rivo volumio[3136]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 12 12:31:16 rivo systemd[1]: Started RAAT DAEMON. Sep 12 12:31:16 rivo sudo[4577]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:16 rivo volumio[3136]: info: Raat Daemon started successfully Sep 12 12:31:16 rivo volumio[3136]: info: Executing endpoint restartRAATSocket Sep 12 12:31:16 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 12 12:31:16 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:16 rivo volumio[3136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 12 12:31:17 rivo volumio[3793]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:31:17 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:17 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:17 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:17 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::volumioGetBrowseSources Sep 12 12:31:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:31:19 rivo volumio[3136]: info: RAAT: Requesting Headphone Status Sep 12 12:31:19 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 12 12:31:19 rivo volumio[3136]: info: MCU Signalled Headphone Mode Disabled Sep 12 12:31:19 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 12 12:31:19 rivo volumio[3136]: info: Reporting Headphone State: false Sep 12 12:31:19 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:19 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:19 rivo volumio[3136]: info: Updating RAAT Signal Path Sep 12 12:31:21 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 12 12:31:21 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 12 12:31:21 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 12 12:31:27 rivo volumio[3136]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/rivoplus/buster/armhf Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 12 12:31:28 rivo volumio[3136]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 12 12:31:28 rivo volumio[3136]: info: Received Get System Version Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:31:28 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:28 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:28 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:28 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 12 12:31:29 rivo volumio[3136]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 12 12:31:29 rivo volumio[3136]: info: Received Get System Version Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:31:29 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:29 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:29 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:29 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:30 rivo volumio[3136]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/rivoplus/buster/armhf Sep 12 12:31:30 rivo volumio[3136]: info: FusionDsp - Stopping FusionDsp service Sep 12 12:31:30 rivo volumio[3136]: info: camilladsp stopping service pid 4544... Sep 12 12:31:30 rivo volumio[3136]: info: camilladsp service terminated, instance 1 Sep 12 12:31:30 rivo sudo[4640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Sep 12 12:31:30 rivo sudo[4640]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:30 rivo volumio[3984]: Backend configuration: Sep 12 12:31:30 rivo volumio[3984]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 12 12:31:30 rivo volumio[3984]: active_config_txt: null Sep 12 12:31:30 rivo volumio[3984]: camilla_host: 127.0.0.1 Sep 12 12:31:30 rivo volumio[3984]: camilla_port: 9876 Sep 12 12:31:30 rivo volumio[3984]: coeff_dir: /data/INTERNAL/FusionDsp/filters Sep 12 12:31:30 rivo volumio[3984]: config_dir: /data/configuration/audio_interface/fusiondsp Sep 12 12:31:30 rivo volumio[3984]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 12 12:31:30 rivo volumio[3984]: log_file: /tmp/camilladsp.log Sep 12 12:31:30 rivo volumio[3984]: on_get_active_config: null Sep 12 12:31:30 rivo volumio[3984]: on_set_active_config: null Sep 12 12:31:30 rivo volumio[3984]: port: 5011 Sep 12 12:31:30 rivo volumio[3984]: supported_capture_types: null Sep 12 12:31:30 rivo volumio[3984]: supported_playback_types: null Sep 12 12:31:30 rivo volumio[3984]: update_config_symlink: false Sep 12 12:31:30 rivo volumio[3984]: update_config_txt: false Sep 12 12:31:30 rivo volumio[3984]: ======== Running on http://0.0.0.0:5011 ======== Sep 12 12:31:30 rivo volumio[3984]: (Press CTRL+C to quit) Sep 12 12:31:30 rivo systemd[1]: Stopping FusionDsp Daemon... Sep 12 12:31:30 rivo volumio[3136]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 12 12:31:30 rivo systemd[1]: fusiondsp.service: Succeeded. Sep 12 12:31:30 rivo systemd[1]: Stopped FusionDsp Daemon. Sep 12 12:31:30 rivo sudo[4640]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:30 rivo volumio[3136]: info: FusionDsp - Reporting Fusion DSP Disabled Sep 12 12:31:30 rivo volumio[3136]: info: Removing fusiondspeq DSP Signal Path Element Sep 12 12:31:30 rivo volumio[3136]: info: Additional DSP elements updated Sep 12 12:31:30 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:30 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:31:30 rivo volumio[3136]: info: Updating RAAT Signal Path Sep 12 12:31:31 rivo volumio[3136]: info: Check plugin dependencies Sep 12 12:31:31 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:31:31 rivo volumio[3136]: info: Rename folder Sep 12 12:31:31 rivo volumio[3136]: info: Folder /tmp/downloaded_plugin.zip removed Sep 12 12:31:31 rivo volumio[3136]: info: Move to category Sep 12 12:31:31 rivo volumio[3136]: info: Checking if install.sh is present Sep 12 12:31:31 rivo volumio[3136]: info: Executing install.sh Sep 12 12:31:31 rivo sudo[4655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Sep 12 12:31:31 rivo sudo[4655]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:31 rivo volumio[3136]: info: creating filters folder and copying demo filters Sep 12 12:31:31 rivo volumio[3136]: info: copying demo flters Sep 12 12:31:32 rivo volumio[3136]: info: Installing/fusiondsp dependencies Sep 12 12:31:32 rivo sudo[4676]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Sep 12 12:31:32 rivo sudo[4676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:32 rivo volumio[3136]: info: Get:1 http://archive.debian.org/debian buster InRelease [122 kB] Sep 12 12:31:32 rivo volumio[3136]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Sep 12 12:31:33 rivo volumio[3136]: info: Get:3 http://archive.debian.org/debian buster/main armhf Packages [7706 kB] Sep 12 12:31:33 rivo volumio[3136]: info: Get:4 http://archive.debian.org/debian buster/main Translation-en [5969 kB] Sep 12 12:31:33 rivo volumio[3136]: info: Get:5 http://archive.debian.org/debian buster/contrib armhf Packages [40.1 kB] Sep 12 12:31:33 rivo volumio[3136]: info: Get:6 http://archive.debian.org/debian buster/contrib Translation-en [44.2 kB] Sep 12 12:31:33 rivo volumio[3136]: info: Get:7 http://archive.debian.org/debian buster/non-free armhf Packages [62.1 kB] Sep 12 12:31:33 rivo volumio[3136]: info: Get:8 http://archive.debian.org/debian buster/non-free Translation-en [88.9 kB] Sep 12 12:31:42 rivo volumio[3793]: ....................................................................................................................................................................................................................................................................................................................................................++++ Sep 12 12:31:44 rivo volumio[3136]: info: Fetched 14.0 MB in 12s (1170 kB/s) Sep 12 12:31:48 rivo volumio[3136]: info: Reading package lists... Sep 12 12:31:49 rivo volumio[3793]: .................................................................................++++ Sep 12 12:31:49 rivo volumio[3793]: e is 65537 (0x010001) Sep 12 12:31:49 rivo volumio[3793]: writing RSA key Sep 12 12:31:49 rivo volumio[3136]: info: Building dependency tree... Sep 12 12:31:49 rivo volumio[3136]: info: Reading state information... Sep 12 12:31:49 rivo volumio[3136]: info: All packages are up to date. Sep 12 12:31:49 rivo sudo[4676]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:49 rivo sudo[4956]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Sep 12 12:31:49 rivo sudo[4956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:49 rivo volumio[3136]: info: Reading package lists... Sep 12 12:31:50 rivo volumio[3136]: info: Building dependency tree... Sep 12 12:31:50 rivo volumio[3136]: info: Reading state information... Sep 12 12:31:50 rivo volumio[3136]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.66 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::volumioGetVisibleSources Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:50 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::volumioGetQueue Sep 12 12:31:50 rivo volumio[3136]: info: CoreStateMachine::getQueue Sep 12 12:31:50 rivo volumio[3136]: info: CorePlayQueue::getQueue Sep 12 12:31:50 rivo volumio[3136]: info: Listing playlists Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 12 12:31:50 rivo volumio[3136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 12 12:31:50 rivo volumio[3136]: info: Received Get System Info Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 12 12:31:50 rivo volumio[3136]: info: Discovery: Getting this device information Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:50 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::volumioGetState Sep 12 12:31:50 rivo volumio[3136]: info: CorePlayQueue::getTrack 0 Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:50 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:31:51 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 12 12:31:52 rivo sudo[4956]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:52 rivo volumio[3136]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Sep 12 12:31:52 rivo volumio[3136]: info: python3-pip is already the newest version (18.1-5). Sep 12 12:31:52 rivo volumio[3136]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Sep 12 12:31:52 rivo sudo[4960]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Sep 12 12:31:52 rivo sudo[4960]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:52 rivo sudo[4960]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:52 rivo volumio[3136]: info: lib/systemd/system/fusiondsp.service Sep 12 12:31:54 rivo volumio[3136]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Sep 12 12:31:54 rivo volumio[3136]: info: more info at http://www.winimage.com/zLibDll/unzip.html Sep 12 12:31:54 rivo volumio[3136]: info: Sep 12 12:31:54 rivo volumio[3136]: info: cgui-1.0.0.zip opened Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/LICENSE.txt Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/README.md Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/build/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/robots.txt Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/logo512.png Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/asset-manifest.json Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/index.html Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/.put_statics_here Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/favicon.ico Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/logo192.png Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Sep 12 12:31:54 rivo sudo[4964]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/manifest.json Sep 12 12:31:54 rivo sudo[4964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/css-variables.css Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/service-worker.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/build/static/ Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/build/static/css/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Sep 12 12:31:54 rivo sudo[4964]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/main.1503f6cd.css Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/build/static/media/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/build/static/js/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.71e11955.js Sep 12 12:31:54 rivo sudo[4966]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Sep 12 12:31:54 rivo sudo[4966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.71e11955.js.map Sep 12 12:31:54 rivo sudo[4966]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/config/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/config/camillagui.yml Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/config/gui-config.yml Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/backend/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/filters_test.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/version.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/filters.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/settings.py Sep 12 12:31:54 rivo sudo[4968]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Sep 12 12:31:54 rivo sudo[4968]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/views.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/filterdefaults.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__init__.py Sep 12 12:31:54 rivo volumio[3136]: info: creating directory: cgui/backend/__pycache__/ Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Sep 12 12:31:54 rivo sudo[4968]: pam_unix(sudo:session): session closed for user root Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/routes.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/backend/filemanagement.py Sep 12 12:31:54 rivo volumio[3136]: info: extracting: cgui/main.py Sep 12 12:32:02 rivo volumio[3136]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Sep 12 12:32:02 rivo volumio[3136]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-53qkhije Sep 12 12:32:05 rivo volumio[3136]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Sep 12 12:32:05 rivo volumio[3136]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Sep 12 12:32:05 rivo volumio[3136]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Sep 12 12:32:05 rivo volumio[3136]: info: Building wheels for collected packages: camilladsp Sep 12 12:32:05 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp: started Sep 12 12:32:06 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Sep 12 12:32:06 rivo volumio[3136]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-ntogkgq4/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Sep 12 12:32:06 rivo volumio[3136]: info: Successfully built camilladsp Sep 12 12:32:08 rivo volumio[3136]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/rivoplus/buster/armhf Sep 12 12:32:09 rivo volumio[3136]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/rivoplus/buster/armhf Sep 12 12:32:09 rivo volumio[3136]: info: Folder /tmp/plugins removed Sep 12 12:32:11 rivo volumio[3136]: info: Check plugin dependencies Sep 12 12:32:11 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 12 12:32:11 rivo volumio[3136]: info: Checking if plugin already exists Sep 12 12:32:11 rivo volumio[3136]: info: Rename folder Sep 12 12:32:11 rivo volumio[3136]: info: Folder /tmp/downloaded_plugin.zip removed Sep 12 12:32:11 rivo volumio[3136]: info: Move to category Sep 12 12:32:12 rivo volumio[3136]: info: Checking if install.sh is present Sep 12 12:32:12 rivo volumio[3136]: info: Executing install.sh Sep 12 12:32:12 rivo sudo[5097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Sep 12 12:32:12 rivo sudo[5097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:32:12 rivo volumio[3136]: info: Installing Go-librespot Sep 12 12:32:12 rivo volumio[3136]: info: Installing Go-librespot Sep 12 12:32:12 rivo volumio[3136]: info: Checking old vollibrespot installs Sep 12 12:32:12 rivo volumio[3136]: info: Checking old vollibrespot installs Sep 12 12:32:12 rivo systemd[1]: Reloading. Sep 12 12:32:13 rivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 12 12:32:13 rivo systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 12 12:32:14 rivo systemd[1]: Starting Daily apt download activities... Sep 12 12:32:14 rivo volumio[3136]: info: Downloading daemon Sep 12 12:32:14 rivo volumio[3136]: info: Downloading daemon Sep 12 12:32:14 rivo volumio[3136]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 12 12:32:14 rivo volumio[3136]: info: Sep 12 12:32:14 rivo volumio[3136]: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Sep 12 12:32:14 rivo volumio[3136]: info: Sep 12 12:32:14 rivo volumio[3136]: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Sep 12 12:32:14 rivo volumio[3136]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-2ynesj0s Sep 12 12:32:14 rivo volumio[3136]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-2ynesj0s Sep 12 12:32:16 rivo systemd[1]: apt-daily.service: Succeeded. Sep 12 12:32:16 rivo systemd[1]: Started Daily apt download activities. Sep 12 12:32:16 rivo systemd[1]: Reloading. Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Sep 12 12:32:17 rivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Sep 12 12:32:17 rivo volumio[3136]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Sep 12 12:32:18 rivo volumio[3136]: info: Building wheels for collected packages: camilladsp-plot Sep 12 12:32:18 rivo volumio[3136]: info: Building wheels for collected packages: camilladsp-plot Sep 12 12:32:18 rivo sudo[5097]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:18 rivo volumio[3136]: info: Install script completed Sep 12 12:32:18 rivo volumio[3136]: info: Adding reference to registry Sep 12 12:32:18 rivo volumio[3136]: info: Done installing plugin. Sep 12 12:32:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:32:18 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 12 12:32:18 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp-plot: started Sep 12 12:32:18 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp-plot: started Sep 12 12:32:18 rivo volumio[3136]: info: Folder /tmp/plugins removed Sep 12 12:32:18 rivo volumio[3136]: info: Folder /tmp/downloaded_plugin.zip removed Sep 12 12:32:18 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:32:18 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:32:18 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:32:18 rivo volumio[3136]: info: Folder /data/temp removed Sep 12 12:32:19 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Sep 12 12:32:19 rivo volumio[3136]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Sep 12 12:32:19 rivo volumio[3136]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-brdhzf00/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Sep 12 12:32:19 rivo volumio[3136]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-brdhzf00/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Sep 12 12:32:19 rivo volumio[3136]: info: Successfully built camilladsp-plot Sep 12 12:32:19 rivo volumio[3136]: info: Successfully built camilladsp-plot Sep 12 12:32:20 rivo volumio[3136]: info: copying hw detection script Sep 12 12:32:20 rivo volumio[3136]: info: copying hw detection script Sep 12 12:32:20 rivo volumio[3136]: info: Detected cpu architecture as aarch64 Sep 12 12:32:20 rivo volumio[3136]: info: Detected cpu architecture as aarch64 Sep 12 12:32:21 rivo volumio[3136]: info: camilladsp Sep 12 12:32:21 rivo volumio[3136]: info: camilladsp Sep 12 12:32:21 rivo sudo[5251]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Sep 12 12:32:21 rivo sudo[5251]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:32:21 rivo sudo[5251]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:21 rivo sudo[5253]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Sep 12 12:32:21 rivo sudo[5253]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:32:21 rivo sudo[5253]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:21 rivo sudo[5255]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Sep 12 12:32:21 rivo sudo[5255]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:32:21 rivo volumio[3136]: info: Reading package lists... Sep 12 12:32:21 rivo volumio[3136]: info: Reading package lists... Sep 12 12:32:22 rivo volumio[3136]: info: Enabling plugin spop Sep 12 12:32:22 rivo volumio[3136]: info: Loading plugin "spop"... Sep 12 12:32:24 rivo volumio[3136]: info: PLUGIN START: spop Sep 12 12:32:24 rivo volumio[3136]: info: Creating Spotify config file Sep 12 12:32:24 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 12 12:32:24 rivo volumio[3136]: info: Done. Sep 12 12:32:24 rivo volumio[3136]: info: Building dependency tree... Sep 12 12:32:24 rivo volumio[3136]: info: Reading state information... Sep 12 12:32:24 rivo volumio[3136]: info: Building dependency tree... Sep 12 12:32:24 rivo volumio[3136]: info: Reading state information... Sep 12 12:32:24 rivo volumio[3136]: info: Spotify config file written Sep 12 12:32:24 rivo sudo[5260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 12 12:32:24 rivo sudo[5260]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 12 12:32:24 rivo volumio[3136]: info: No need to fix Spotify hosts Sep 12 12:32:24 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:32:24 rivo volumio[3136]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 12 12:32:24 rivo sudo[5255]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:24 rivo sudo[4655]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:24 rivo systemd[1]: Started go-librespot Daemon. Sep 12 12:32:24 rivo sudo[5260]: pam_unix(sudo:session): session closed for user root Sep 12 12:32:24 rivo go-librespot[5262]: go-librespot daemon starting... Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=info msg="running go-librespot 0.3.2" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=debug msg="no app state found" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=debug msg="stored credentials not found" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=info msg="generated new device id: 87e51de74a5570e0679eee75756db9b3631b47ca" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 12 12:32:24 rivo go-librespot[5262]: time="2025-09-12T12:32:24+01:00" level=info msg="zeroconf server listening on port 37429" Sep 12 12:32:24 rivo volumio[3136]: info: Install script completed Sep 12 12:32:24 rivo volumio[3136]: info: Done installing plugin. Sep 12 12:32:24 rivo volumio[3136]: info: Enabling plugin fusiondsp Sep 12 12:32:24 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 12 12:32:24 rivo volumio[3136]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 12 12:32:24 rivo volumio[3136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 12 12:32:24 rivo volumio[3136]: info: Folder /data/temp removed Sep 12 12:32:24 rivo volumio[3136]: info: Folder /tmp/plugins removed Sep 12 12:32:24 rivo volumio[3136]: info: Folder /tmp/downloaded_plugin.zip removed Sep 12 12:32:25 rivo volumio[3136]: info: drc is already the newest version (3.2.2~dfsg0-2). Sep 12 12:32:25 rivo volumio[3136]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Sep 12 12:32:25 rivo volumio[3136]: Plugin install end detected on script Sep 12 12:32:25 rivo volumio[3136]: info: drc is already the newest version (3.2.2~dfsg0-2). Sep 12 12:32:25 rivo volumio[3136]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Sep 12 12:32:25 rivo volumio[3136]: Plugin install end detected on script Sep 12 12:32:27 rivo volumio[3136]: info: go-librespot daemon successfully initialized Sep 12 12:32:30 rivo volumio[3136]: info: Initializing connection to go-librespot Websocket Sep 12 12:32:30 rivo go-librespot[5262]: time="2025-09-12T12:32:30+01:00" level=debug msg="new websocket client" Sep 12 12:32:30 rivo volumio[3136]: info: Connection to go-librespot Websocket established Sep 12 12:32:31 rivo volumio[3136]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/rivoplus/buster/armhf Sep 12 12:32:33 rivo volumio[3136]: info: Getting Spotify volume Sep 12 12:32:34 rivo volumio[3136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 12 12:32:34 rivo volumio[3136]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/fusiondsp/rivoplus/buster/armhf Sep 12 12:32:34 rivo volumio[3136]: info: FusionDsp - Stopping FusionDsp service Sep 12 12:32:34 rivo volumio[3136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 12:32:34 rivo volumio[3136]: TypeError: Cannot read property 'stop' of null Sep 12 12:32:34 rivo volumio[3136]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Sep 12 12:32:34 rivo volumio[3136]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 12 12:32:34 rivo volumio[3136]: at /volumio/app/pluginmanager.js:956:12 Sep 12 12:32:34 rivo volumio[3136]: at ChildProcess.exithandler (child_process.js:374:7) Sep 12 12:32:34 rivo volumio[3136]: at ChildProcess.emit (events.js:400:28) Sep 12 12:32:34 rivo volumio[3136]: at maybeClose (internal/child_process.js:1088:16) Sep 12 12:32:34 rivo volumio[3136]: at Socket. (internal/child_process.js:446:11) Sep 12 12:32:34 rivo volumio[3136]: at Socket.emit (events.js:400:28) Sep 12 12:32:34 rivo volumio[3136]: at Pipe. (net.js:686:12) Sep 12 12:32:34 rivo volumio[3136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 12:32:34 rivo sudo[5310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-12 12:31 Sep 12 12:32:34 rivo sudo[5310]: 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="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9a3927796ae9ab3a5a247866bf574288cd09ee64" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 03 Sep 2025 08:56:45 PM CEST" VOLUMIO_VERSION="3.843" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="2b1664f03d4ede52250d70c9b2e19f59"