-- Logs begin at Thu 2019-02-14 18:11:58 AWST, end at Tue 2025-04-15 16:04:47 AWST. -- Apr 15 16:04:35 rivoplus systemd-timedated[3394]: Changed local time to Tue Apr 15 16:04:35 2025 Apr 15 16:04:35 rivoplus sudo[3392]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:35 rivoplus volumio-time-update[2838]: volumio-time-update-util: System time updated successfully. Apr 15 16:04:35 rivoplus systemd[1]: Started Volumio Time Update Utility. Apr 15 16:04:35 rivoplus systemd[1]: Starting Daily apt download activities... Apr 15 16:04:35 rivoplus systemd[1]: Starting Daily man-db regeneration... Apr 15 16:04:35 rivoplus volumio[3128]: Starting albumart workers Apr 15 16:04:35 rivoplus volumio[3128]: Starting albumart workers Apr 15 16:04:35 rivoplus volumio[3128]: Starting albumart workers Apr 15 16:04:36 rivoplus volumio[3128]: info: Applying required configuration parameters for plugin hi_res_audio Apr 15 16:04:36 rivoplus volumio[3128]: info: Loading plugin "inputs"... Apr 15 16:04:36 rivoplus kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Apr 15 16:04:36 rivoplus systemd[1]: Started Samba NMB Daemon. Apr 15 16:04:36 rivoplus nmbd[3080]: [2025/04/15 16:04:36.111173, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 15 16:04:36 rivoplus nmbd[3080]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 15 16:04:36 rivoplus systemd[1]: Starting Samba Winbind Daemon... Apr 15 16:04:36 rivoplus systemd[1]: man-db.service: Succeeded. Apr 15 16:04:36 rivoplus systemd[1]: Started Daily man-db regeneration. Apr 15 16:04:36 rivoplus winbindd[3434]: [2025/04/15 16:04:36.554928, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 15 16:04:36 rivoplus winbindd[3434]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 15 16:04:36 rivoplus winbindd[3434]: [2025/04/15 16:04:36.568402, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 15 16:04:36 rivoplus systemd[1]: Started Samba Winbind Daemon. Apr 15 16:04:36 rivoplus winbindd[3434]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 15 16:04:36 rivoplus systemd[1]: Starting Samba SMB Daemon... Apr 15 16:04:37 rivoplus volumio[3128]: info: Loading plugin "qobuz"... Apr 15 16:04:37 rivoplus smbd[3447]: [2025/04/15 16:04:37.161510, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 15 16:04:37 rivoplus systemd[1]: Started Samba SMB Daemon. Apr 15 16:04:37 rivoplus smbd[3447]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 15 16:04:37 rivoplus systemd[1]: Reached target Multi-User System. Apr 15 16:04:37 rivoplus systemd[1]: Reached target Graphical Interface. Apr 15 16:04:37 rivoplus volumio-remote-updater[2854]: [2025-04-15 16:04:37] [connect] Successful connection Apr 15 16:04:37 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 15 16:04:37 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 15 16:04:37 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 15 16:04:37 rivoplus systemd[1]: apt-daily.service: Succeeded. Apr 15 16:04:37 rivoplus systemd[1]: Started Daily apt download activities. Apr 15 16:04:37 rivoplus systemd[1]: Starting Daily apt upgrade and clean activities... Apr 15 16:04:39 rivoplus volumio[3128]: info: Plugin smart_inputs is not enabled Apr 15 16:04:39 rivoplus volumio[3128]: info: Loading plugin "tidal"... Apr 15 16:04:39 rivoplus systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 15 16:04:39 rivoplus systemd[1]: Started Daily apt upgrade and clean activities. Apr 15 16:04:39 rivoplus systemd[1]: Startup finished in 12.853s (kernel) + 25.626s (userspace) = 38.480s. Apr 15 16:04:41 rivoplus volumio[3128]: info: Loading plugin "rivopluscontrol"... Apr 15 16:04:41 rivoplus volumio[3128]: info: Adding this device properties Apr 15 16:04:41 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 15 16:04:41 rivoplus volumio[3128]: info: Setting Additional Device Volumio Properties: [object Object] Apr 15 16:04:41 rivoplus volumio[3128]: info: Loading plugin "updater_comm"... Apr 15 16:04:41 rivoplus volumio[3128]: info: Loading plugin "cec_controller"... Apr 15 16:04:42 rivoplus volumio[3128]: info: Applying required configuration parameters for plugin cec_controller Apr 15 16:04:42 rivoplus volumio[3128]: info: Plugin mpdemulation is not enabled Apr 15 16:04:42 rivoplus volumio[3128]: info: Loading plugin "rest_api"... Apr 15 16:04:42 rivoplus volumio[3128]: info: Loading plugin "websocket"... Apr 15 16:04:42 rivoplus volumio[3128]: info: Starting Socket.io Server version 2.3.0 Apr 15 16:04:42 rivoplus volumio[3128]: info: Loading plugin "fusiondsp"... Apr 15 16:04:42 rivoplus volumio[3128]: info: Applying required configuration parameters for plugin fusiondsp Apr 15 16:04:42 rivoplus volumio[3128]: info: Loading i18n strings for locale en Apr 15 16:04:42 rivoplus volumio[3128]: Updating browse sources language Apr 15 16:04:42 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::initPlayerControls Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: Express server listening on port 3000 Apr 15 16:04:43 rivoplus volumio[3128]: [Metrics] WebUI: 20s 158.98ms Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::resetVolumioState Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::getcurrentVolume Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:43 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 15 16:04:43 rivoplus volumio[3128]: info: Completed loading Core Plugins Apr 15 16:04:43 rivoplus volumio[3128]: info: Preparing to generate the ALSA configuration file Apr 15 16:04:43 rivoplus volumio[3128]: info: Volumio Network Manager: Network status updated: 0 Apr 15 16:04:43 rivoplus volumio[3128]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 15 16:04:43 rivoplus volumio[3128]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 16:04:43 rivoplus volumio[3128]: info: Reading ALSA contributions from plugins. Apr 15 16:04:43 rivoplus volumio[3128]: info: Reloading queue from file Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::setRepeat null single undefined Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:43 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::setRandom null Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:43 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:43 rivoplus volumio[3128]: info: Asound.conf file unchanged, so no further update is needed Apr 15 16:04:43 rivoplus volumio[3128]: info: Output device has changed, restarting MPD Apr 15 16:04:43 rivoplus sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 16:04:43 rivoplus volumio[3128]: info: ___________ START PLUGINS ___________ Apr 15 16:04:43 rivoplus sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3561]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:43 rivoplus volumio[3128]: info: ControllerMpd::onStart: Initializing MPD Apr 15 16:04:43 rivoplus volumio[3128]: info: Creating MPD Configuration file Apr 15 16:04:43 rivoplus sudo[3563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 16:04:43 rivoplus sudo[3563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus systemd[1]: Stopping Music Player Daemon... Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding METAVOLUMIO REST API Endpoints Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 16:04:43 rivoplus volumio[3128]: info: [1744704283546] CoreMusicLibrary::Adding element Last_100 Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 16:04:43 rivoplus volumio[3128]: info: Streaming services startup Apr 15 16:04:43 rivoplus volumio[3128]: info: Starting Streaming Daemon Apr 15 16:04:43 rivoplus sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 16:04:43 rivoplus sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 16:04:43 rivoplus sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3567]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:43 rivoplus sudo[3573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 15 16:04:43 rivoplus sudo[3573]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3573]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 16:04:43 rivoplus volumio[3128]: info: [1744704283666] CoreMusicLibrary::Adding element Webradio Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 16:04:43 rivoplus volumio[3128]: info: Initializing BBC Radios Apr 15 16:04:43 rivoplus systemd[1]: mpd.service: Succeeded. Apr 15 16:04:43 rivoplus systemd[1]: Stopped Music Player Daemon. Apr 15 16:04:43 rivoplus systemd[1]: Starting Music Player Daemon... Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 16:04:43 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 16:04:43 rivoplus volumio[3128]: error: Hi Res Audio Failed Login: Missing Login Data Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 15 16:04:43 rivoplus volumio[3128]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 15 16:04:43 rivoplus volumio[3128]: info: Initializing Serial Communication on port /dev/ttyS3 Apr 15 16:04:43 rivoplus volumio[3128]: info: Touch Event Listener Process Starting Apr 15 16:04:43 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Apr 15 16:04:43 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Apr 15 16:04:43 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Apr 15 16:04:43 rivoplus volumio[3128]: info: Refreshing QOBUZ token Apr 15 16:04:43 rivoplus sudo[3600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Apr 15 16:04:43 rivoplus sudo[3600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3600]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:43 rivoplus sudo[3584]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 16:04:43 rivoplus sudo[3584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3584]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:43 rivoplus sudo[3612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Apr 15 16:04:43 rivoplus sudo[3612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:43 rivoplus sudo[3612]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Apr 15 16:04:44 rivoplus sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus sudo[3616]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus sudo[3619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Apr 15 16:04:44 rivoplus sudo[3619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus sudo[3619]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Apr 15 16:04:44 rivoplus sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus sudo[3622]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus volumio[3128]: info: Apply VIM3L Onboard LEDs Settings Apr 15 16:04:44 rivoplus volumio[3128]: info: Turning On Onboard LEDs Apr 15 16:04:44 rivoplus sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 15 16:04:44 rivoplus sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus volumio[3128]: info: Starting CEC Event listener Apr 15 16:04:44 rivoplus sudo[3626]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus volumio[3128]: info: Loading i18n strings for locale en Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: FusionDsp - mixtype--------------------- None Apr 15 16:04:44 rivoplus volumio[3128]: info: Volumio Calling Home Apr 15 16:04:44 rivoplus sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 15 16:04:44 rivoplus sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus sudo[3630]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus volumio[3128]: info: Preparing to generate the ALSA configuration file Apr 15 16:04:44 rivoplus volumio[3128]: info: Stopping AccessToken refresher cron Apr 15 16:04:44 rivoplus volumio[3128]: info: AccessToken refresher cron started Apr 15 16:04:44 rivoplus volumio[3128]: info: Adding TIDAL REST API Endpoints Apr 15 16:04:44 rivoplus volumio[3128]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 15 16:04:44 rivoplus volumio[3128]: info: Discovery: adding 8fc38d11-067e-4a43-af74-5cec64254c51 Apr 15 16:04:44 rivoplus volumio[3128]: info: Discovery: Found device Rivoplus Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::volumioGetState Apr 15 16:04:44 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:44 rivoplus volumio[3128]: info: Serial port opened successfully Apr 15 16:04:44 rivoplus volumio[3128]: info: Sending serial start messages Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: Reporting MCU Network Status: 0 Apr 15 16:04:44 rivoplus volumio[3128]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 16:04:44 rivoplus volumio[3128]: info: Reading ALSA contributions from plugins. Apr 15 16:04:44 rivoplus volumio[3128]: info: Touch Event Listener Process Closed Apr 15 16:04:44 rivoplus volumio[3128]: error: Cannot start Volumio Streaming Daemon Apr 15 16:04:44 rivoplus volumio[3128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 15 16:04:44 rivoplus volumio[3128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 15 16:04:44 rivoplus volumio[3128]: info: MPD Permissions set Apr 15 16:04:44 rivoplus volumio[3128]: info: MPD Permissions set Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting Device Volume Override Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 15 16:04:44 rivoplus volumio[3128]: info: Updating Volume Controller Parameters: Device: 5 Name: SPL Diamond Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 15 16:04:44 rivoplus volumio[3128]: info: Disabling external Volume Control Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:44 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:44 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:44 rivoplus volumio[3128]: info: Discovery: A device disappeared from network Apr 15 16:04:44 rivoplus volumio[3128]: info: Discovery: Device rivoplus disappeared from network Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting Additional System Software info: Hardware Revision: 1.0 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting HW Firmware info: undefined Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting HW Version info: 1.0 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.7 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting HW Firmware info: 0.2.7 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 15 16:04:44 rivoplus volumio[3128]: info: Setting HW Version info: 1.0 Apr 15 16:04:44 rivoplus volumio[3128]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 16:04:44 rivoplus volumio[3128]: info: MCU Signalled Headphone Mode Disabled Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 16:04:44 rivoplus volumio[3128]: info: MCU Signalled Sleep Mode Disabled Apr 15 16:04:44 rivoplus volumio[3128]: info: Enabling Advanced system settings configuration Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 15 16:04:44 rivoplus volumio[3128]: info: Additional UI Settings Added for plugin music_service/inputs Apr 15 16:04:44 rivoplus volumio[3128]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 15 16:04:44 rivoplus sudo[3639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 15 16:04:44 rivoplus sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:44 rivoplus sudo[3639]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:44 rivoplus volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 16:04:44 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 15 16:04:45 rivoplus volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 15 16:04:45 rivoplus sudo[3659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Apr 15 16:04:45 rivoplus sudo[3659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:45 rivoplus sudo[3659]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:45 rivoplus volumio[3128]: info: Volumio called home Apr 15 16:04:45 rivoplus volumio[3128]: info: Discovery: adding 8fc38d11-067e-4a43-af74-5cec64254c51 Apr 15 16:04:45 rivoplus volumio[3128]: info: Discovery: Found device Rivoplus Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumioGetState Apr 15 16:04:45 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:45 rivoplus volumio[3128]: info: Adding Advanced Audio Settings via Serial API Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 15 16:04:45 rivoplus volumio[3128]: info: Additional UI Settings Added for plugin music_service/inputs Apr 15 16:04:45 rivoplus volumio[3128]: info: Adding Inputs via Serial API Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumioGetState Apr 15 16:04:45 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:45 rivoplus volumio[3128]: info: MP1 GPIO: Signalled system ready via GPIO Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumioGetState Apr 15 16:04:45 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:45 rivoplus volumio[3128]: info: Access Token successfully retrieved Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 16:04:45 rivoplus volumio[3128]: info: [1744704285461] CoreMusicLibrary::Adding element QOBUZ Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 16:04:45 rivoplus volumio[3128]: Cannot find translation for source QOBUZ Apr 15 16:04:45 rivoplus volumio[3128]: info: Stopping AccessToken refresher cron for QOBUZ Apr 15 16:04:45 rivoplus volumio[3128]: info: AccessToken refresher cron started for QOBUZ Apr 15 16:04:45 rivoplus volumio[3128]: info: Adding QOBUZ REST API Endpoints Apr 15 16:04:45 rivoplus volumio[3128]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 15 16:04:45 rivoplus volumio[3128]: info: Asound.conf file unchanged, so no further update is needed Apr 15 16:04:45 rivoplus volumio[3128]: info: Output device has changed, restarting MPD Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 16:04:45 rivoplus sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreStateMachine::pushState Apr 15 16:04:45 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumioPushState Apr 15 16:04:45 rivoplus sudo[3665]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::servicePushState Apr 15 16:04:45 rivoplus volumio[3128]: info: CorePlayQueue::getTrack 0 Apr 15 16:04:45 rivoplus volumio[3128]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 16:04:45 rivoplus volumio[3128]: Cannot find translation for source QOBUZ Apr 15 16:04:45 rivoplus sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 16:04:45 rivoplus sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:45 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 16:04:45 rivoplus systemd[1]: mpd.service: Succeeded. Apr 15 16:04:45 rivoplus volumio[3128]: info: MPD Permissions set Apr 15 16:04:45 rivoplus systemd[1]: Stopped Music Player Daemon. Apr 15 16:04:45 rivoplus systemd[1]: Starting Music Player Daemon... Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 16:04:45 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:45 rivoplus sudo[3670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 16:04:45 rivoplus sudo[3670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:45 rivoplus sudo[3670]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:46 rivoplus volumio[3128]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 15 16:04:46 rivoplus volumio[3128]: info: camilladsp service started and running in background, instance 1 Apr 15 16:04:46 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 16:04:46 rivoplus volumio[3128]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 15 16:04:46 rivoplus volumio[3128]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 15 16:04:46 rivoplus volumio[3128]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 15 16:04:46 rivoplus volumio[3128]: info: FusionDsp loaded Apr 15 16:04:46 rivoplus volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 16:04:46 rivoplus sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 16:04:46 rivoplus sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 16:04:46 rivoplus volumio[3128]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 16:04:46 rivoplus volumio[3128]: info: Adding Signal Path Element [object Object] Apr 15 16:04:46 rivoplus volumio[3128]: info: Adding fusiondspeq DSP Signal Path Element Apr 15 16:04:46 rivoplus volumio[3128]: info: FusionDsp - ---- installed callbackRead Apr 15 16:04:46 rivoplus volumio[3128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 16:04:46 rivoplus volumio[3128]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 15 16:04:46 rivoplus volumio[3128]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 15 16:04:46 rivoplus volumio[3128]: at onErrorNT (internal/child_process.js:472:16) Apr 15 16:04:46 rivoplus volumio[3128]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 15 16:04:46 rivoplus volumio[3128]: errno: -2, Apr 15 16:04:46 rivoplus volumio[3128]: code: 'ENOENT', Apr 15 16:04:46 rivoplus volumio[3128]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 15 16:04:46 rivoplus volumio[3128]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 15 16:04:46 rivoplus volumio[3128]: spawnargs: [ Apr 15 16:04:46 rivoplus volumio[3128]: '-p', Apr 15 16:04:46 rivoplus volumio[3128]: 9876, Apr 15 16:04:46 rivoplus volumio[3128]: '-o', Apr 15 16:04:46 rivoplus volumio[3128]: '/tmp/camilladsp.log', Apr 15 16:04:46 rivoplus volumio[3128]: '-l', Apr 15 16:04:46 rivoplus volumio[3128]: 'warn', Apr 15 16:04:46 rivoplus volumio[3128]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 15 16:04:46 rivoplus volumio[3128]: ] Apr 15 16:04:46 rivoplus volumio[3128]: } Apr 15 16:04:46 rivoplus volumio[3128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 16:04:46 rivoplus systemd[1]: Started FusionDsp Daemon. Apr 15 16:04:46 rivoplus sudo[3690]: pam_unix(sudo:session): session closed for user root Apr 15 16:04:47 rivoplus sudo[3702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-15 16:03 Apr 15 16:04:47 rivoplus sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:42:11 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="4f5519a96ae5ba7dcfda362cc37343d4"