-- Logs begin at Sun 2025-12-07 18:53:25 CET, end at Mon 2025-12-08 09:19:26 CET. -- Dec 08 09:18:18 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 4 Dec 08 09:18:18 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:18 rivoplus volumio[3105]: info: Prefetching next song Dec 08 09:18:18 rivoplus volumio[3105]: info: [1765181898923] ControllerQobuz::prefetch Dec 08 09:18:18 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/70522199" Dec 08 09:18:18 rivoplus volumio[3105]: STREAMING PROXY: Handling url /?data=qobuz://song/70522199 Dec 08 09:18:18 rivoplus volumio[3105]: info: Executing endpoint getStreamUrlqobuz Dec 08 09:18:18 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 08 09:18:19 rivoplus volumio[3105]: info: getStreamUrl took 194 milliseconds Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=4980416&eid=70522199&fmt=6&profile=raw&app_id=539451548&cid=3114315&etsp=1765185499&hmac=NTRdXZTBd-62VcQZOAEO8pkEmoA Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Response: 200, length: 18265569 Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Client dropped request, destroying Dec 08 09:18:19 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/70522199" Dec 08 09:18:19 rivoplus volumio[3105]: info: Dec 08 09:18:19 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:19 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:19 rivoplus volumio[3105]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/70522199" took 2 milliseconds Dec 08 09:18:19 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 08 09:18:19 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 08 09:18:19 rivoplus volumio[3105]: info: Dec 08 09:18:19 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:19 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:19 rivoplus volumio[3105]: info: Dec 08 09:18:19 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:19 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Handling url /?data=qobuz://song/70522199 Dec 08 09:18:19 rivoplus volumio[3105]: info: ------------------------------ 9ms Dec 08 09:18:19 rivoplus volumio[3105]: info: sendMpdCommand consume 1 took 9 milliseconds Dec 08 09:18:19 rivoplus volumio[3105]: info: ------------------------------ 9ms Dec 08 09:18:19 rivoplus volumio[3105]: info: ------------------------------ 8ms Dec 08 09:18:19 rivoplus volumio[3105]: info: Executing endpoint getStreamUrlqobuz Dec 08 09:18:19 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 08 09:18:19 rivoplus volumio[3105]: info: getStreamUrl took 173 milliseconds Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=4980416&eid=70522199&fmt=6&profile=raw&app_id=539451548&cid=3114315&etsp=1765185499&hmac=NTRdXZTBd-62VcQZOAEO8pkEmoA Dec 08 09:18:19 rivoplus volumio[3105]: STREAMING PROXY: Response: 200, length: 18265569 Dec 08 09:18:22 rivoplus volumio[3105]: STREAMING PROXY: Client dropped request, destroying Dec 08 09:18:23 rivoplus volumio[3105]: info: CoreStateMachine::startPlaybackTimer Dec 08 09:18:23 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:23 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:23 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:23 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 09:18:23 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:23 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:24 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::getState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:24 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::getState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Dec 08 09:18:24 rivoplus volumio[3105]: info: Ignoring MPD Status Update Dec 08 09:18:24 rivoplus volumio[3105]: info: Dec 08 09:18:24 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::getState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 9ms Dec 08 09:18:24 rivoplus volumio[3105]: info: sendMpdCommand status took 7 milliseconds Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 7ms Dec 08 09:18:24 rivoplus volumio[3105]: info: sendMpdCommand status took 6 milliseconds Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 4ms Dec 08 09:18:24 rivoplus volumio[3105]: info: sendMpdCommand status took 3 milliseconds Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 09:18:24 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 08 09:18:24 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Dec 08 09:18:24 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"70522199","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/70522199","trackType":"qobuz"} Dec 08 09:18:24 rivoplus volumio[3105]: verbose: CURRENT POSITION 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Dec 08 09:18:24 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"70522199","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/70522199","trackType":"qobuz"} Dec 08 09:18:24 rivoplus volumio[3105]: verbose: CURRENT POSITION 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Dec 08 09:18:24 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: ControllerMpd::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"70522199","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/70522199","trackType":"qobuz"} Dec 08 09:18:24 rivoplus volumio[3105]: verbose: CURRENT POSITION 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Dec 08 09:18:24 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreStateMachine::pushState Dec 08 09:18:24 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 5 Dec 08 09:18:24 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 40ms Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 38ms Dec 08 09:18:24 rivoplus volumio[3105]: info: ------------------------------ 37ms Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:18:24 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Dec 08 09:19:13 rivoplus volumio[3105]: info: Enabling plugin fusiondsp Dec 08 09:19:13 rivoplus volumio[3105]: info: Loading plugin "fusiondsp"... Dec 08 09:19:14 rivoplus volumio[3105]: info: Preparing to generate the ALSA configuration file Dec 08 09:19:14 rivoplus volumio[3105]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 08 09:19:14 rivoplus volumio[3105]: info: Reading ALSA contributions from plugins. Dec 08 09:19:14 rivoplus volumio[3105]: info: Asound.conf file written Dec 08 09:19:14 rivoplus sudo[29321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 08 09:19:14 rivoplus sudo[29321]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus sudo[29321]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:14 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Dec 08 09:19:14 rivoplus kernel: Err: can not set rate to 4278967296! Dec 08 09:19:14 rivoplus kernel: Range[3920000 - 500000000] Dec 08 09:19:14 rivoplus kernel: out of value, fixed it Dec 08 09:19:14 rivoplus kernel: id=0 set inskew=0 Dec 08 09:19:14 rivoplus volumio[3105]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Dec 08 09:19:14 rivoplus volumio[3105]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Dec 08 09:19:14 rivoplus volumio[3105]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Dec 08 09:19:14 rivoplus volumio[3105]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Dec 08 09:19:14 rivoplus volumio[3105]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Dec 08 09:19:14 rivoplus volumio[3105]: info: Output device has changed, restarting MPD Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: PLUGIN START: fusiondsp Dec 08 09:19:14 rivoplus sudo[29327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 09:19:14 rivoplus sudo[29327]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus volumio[3105]: info: Loading i18n strings for locale de Dec 08 09:19:14 rivoplus sudo[29327]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: FusionDsp - mixtype--------------------- None Dec 08 09:19:14 rivoplus volumio[3105]: info: Preparing to generate the ALSA configuration file Dec 08 09:19:14 rivoplus sudo[29328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 09:19:14 rivoplus sudo[29328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus volumio[3105]: info: Done. Dec 08 09:19:14 rivoplus volumio[3105]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 08 09:19:14 rivoplus volumio[3105]: info: Reading ALSA contributions from plugins. Dec 08 09:19:14 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 08 09:19:14 rivoplus volumio[3105]: info: MPD Permissions set Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: Asound.conf file unchanged, so no further update is needed Dec 08 09:19:14 rivoplus volumio[3105]: info: Output device has changed, restarting MPD Dec 08 09:19:14 rivoplus sudo[29338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 09:19:14 rivoplus sudo[29338]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus sudo[29338]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:14 rivoplus sudo[29341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 09:19:14 rivoplus sudo[29341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus systemd[1]: mpd.service: Succeeded. Dec 08 09:19:14 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 08 09:19:14 rivoplus systemd[1]: Starting Music Player Daemon... Dec 08 09:19:14 rivoplus sudo[29343]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 08 09:19:14 rivoplus sudo[29343]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:14 rivoplus sudo[29343]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 09:19:14 rivoplus volumio[3105]: info: MPD Permissions set Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:14 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:15 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 09:19:15 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 08 09:19:15 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:16 rivoplus mpd[29354]: Dec 08 09:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 08 09:19:16 rivoplus systemd[1]: Started Music Player Daemon. Dec 08 09:19:16 rivoplus sudo[29341]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:16 rivoplus sudo[29328]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:19 rivoplus volumio[3105]: info: camilladsp spawned new process with pid 29386, instance 1, run: true Dec 08 09:19:19 rivoplus volumio[3105]: info: camilladsp service started and running in background, instance 1 Dec 08 09:19:19 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 09:19:19 rivoplus volumio[3105]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Dec 08 09:19:19 rivoplus volumio[3105]: info: FusionDsp loaded Dec 08 09:19:19 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 09:19:19 rivoplus sudo[29395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 08 09:19:19 rivoplus sudo[29395]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:19 rivoplus volumio[3105]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 08 09:19:19 rivoplus volumio[3105]: info: Adding Signal Path Element [object Object] Dec 08 09:19:19 rivoplus volumio[3105]: info: Adding fusiondspeq DSP Signal Path Element Dec 08 09:19:19 rivoplus volumio[3105]: info: FusionDsp - ---- installed callbackRead Dec 08 09:19:19 rivoplus volumio[3105]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 08 09:19:19 rivoplus systemd[1]: Started FusionDsp Daemon. Dec 08 09:19:19 rivoplus sudo[29395]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:20 rivoplus volumio[3105]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Dec 08 09:19:20 rivoplus volumio[3105]: error: FusionDsp - WebSocket error: [object Object] Dec 08 09:19:20 rivoplus volumio[3105]: error: MPD error: The expression evaluated to a falsy value: Dec 08 09:19:20 rivoplus volumio[3105]: assert.ok(self.idling) Dec 08 09:19:20 rivoplus volumio[3105]: error: The expression evaluated to a falsy value: Dec 08 09:19:20 rivoplus volumio[3105]: assert.ok(self.idling) Dec 08 09:19:20 rivoplus volumio[3105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 08 09:19:20 rivoplus volumio[3105]: error: updateQueue error: null Dec 08 09:19:21 rivoplus volumio[3105]: 0+0 records in Dec 08 09:19:21 rivoplus volumio[3105]: 0+0 records out Dec 08 09:19:21 rivoplus volumio[3105]: 0 bytes copied, 0.000191542 s, 0.0 kB/s Dec 08 09:19:22 rivoplus volumio[3105]: info: camilladsp spawned new process with pid 29404, instance 1, run: true Dec 08 09:19:23 rivoplus volumio[3105]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Dec 08 09:19:23 rivoplus volumio[3105]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Dec 08 09:19:23 rivoplus volumio[3105]: info: FusionDsp - Stopping FusionDsp service Dec 08 09:19:23 rivoplus volumio[3105]: info: camilladsp stopping service pid 29404... Dec 08 09:19:23 rivoplus volumio[3105]: info: camilladsp service terminated, instance 1 Dec 08 09:19:23 rivoplus sudo[29417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Dec 08 09:19:23 rivoplus sudo[29417]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:23 rivoplus systemd[1]: Stopping FusionDsp Daemon... Dec 08 09:19:23 rivoplus volumio[29398]: Backend configuration: Dec 08 09:19:23 rivoplus volumio[29398]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Dec 08 09:19:23 rivoplus volumio[29398]: active_config_txt: null Dec 08 09:19:23 rivoplus volumio[29398]: camilla_host: 127.0.0.1 Dec 08 09:19:23 rivoplus volumio[29398]: camilla_port: 9876 Dec 08 09:19:23 rivoplus volumio[29398]: coeff_dir: /data/INTERNAL/FusionDsp/filters Dec 08 09:19:23 rivoplus volumio[29398]: config_dir: /data/configuration/audio_interface/fusiondsp Dec 08 09:19:23 rivoplus volumio[29398]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Dec 08 09:19:23 rivoplus volumio[29398]: log_file: /tmp/camilladsp.log Dec 08 09:19:23 rivoplus volumio[29398]: on_get_active_config: null Dec 08 09:19:23 rivoplus volumio[29398]: on_set_active_config: null Dec 08 09:19:23 rivoplus volumio[29398]: port: 5011 Dec 08 09:19:23 rivoplus volumio[29398]: supported_capture_types: null Dec 08 09:19:23 rivoplus volumio[29398]: supported_playback_types: null Dec 08 09:19:23 rivoplus volumio[29398]: update_config_symlink: false Dec 08 09:19:23 rivoplus volumio[29398]: update_config_txt: false Dec 08 09:19:23 rivoplus volumio[29398]: ======== Running on http://0.0.0.0:5011 ======== Dec 08 09:19:23 rivoplus volumio[29398]: (Press CTRL+C to quit) Dec 08 09:19:23 rivoplus volumio[3105]: info: Check plugin dependencies Dec 08 09:19:23 rivoplus volumio[3105]: error: Error finding native modules: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name "*.node" 2>/dev/null {"status":1,"signal":null,"output":[null,"",""],"pid":29421,"stdout":"","stderr":"","stack":"Error: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name \"*.node\" 2>/dev/null\n at checkExecSyncError (child_process.js:790:11)\n at execSync (child_process.js:863:15)\n at PluginManager.checkPluginDependencies (/volumio/app/pluginmanager.js:1149:26)\n at nextTickCallback (/data/plugins/audio_interface/fusiondsp/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Dec 08 09:19:23 rivoplus volumio[3105]: info: An error occurred installing the plugin. Rolling back config Dec 08 09:19:23 rivoplus volumio[3105]: info: Plugin folders cleanup Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning into folder /volumio/app/plugins/ Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category audio_interface Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category miscellanea Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category music_service Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category plugins.json Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category system_controller Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category user_interface Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning into folder /data/plugins/ Dec 08 09:19:23 rivoplus volumio[3105]: info: Scanning category audio_interface Dec 08 09:19:23 rivoplus volumio[3105]: info: Plugin folders cleanup completed Dec 08 09:19:23 rivoplus volumio[3105]: info: Error: Error Dec 08 09:19:23 rivoplus volumio[3105]: info: Error: Error Dec 08 09:19:23 rivoplus volumio[3105]: info: Folder /tmp/plugins removed Dec 08 09:19:23 rivoplus volumio[3105]: info: Folder /tmp/downloaded_plugin.zip removed Dec 08 09:19:23 rivoplus volumio[3105]: info: Folder /tmp/downloaded_plugin.zip removed Dec 08 09:19:23 rivoplus volumio[3105]: info: Folder /data/temp removed Dec 08 09:19:23 rivoplus systemd[1]: fusiondsp.service: Succeeded. Dec 08 09:19:23 rivoplus systemd[1]: Stopped FusionDsp Daemon. Dec 08 09:19:23 rivoplus sudo[29417]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:23 rivoplus volumio[3105]: info: FusionDsp - Reporting Fusion DSP Disabled Dec 08 09:19:23 rivoplus volumio[3105]: info: Removing fusiondspeq DSP Signal Path Element Dec 08 09:19:23 rivoplus sudo[29426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 09:19:23 rivoplus sudo[29426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:24 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 08 09:19:24 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 08 09:19:24 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 08 09:19:24 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 08 09:19:24 rivoplus sudo[29429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 09:19:24 rivoplus sudo[29429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 09:19:24 rivoplus systemd[1]: Started MPD Monitor Service. Dec 08 09:19:24 rivoplus sudo[29426]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:24 rivoplus volumio[3105]: info: Successfully started MPD Monitor Dec 08 09:19:24 rivoplus mpd_monitor.sh[29430]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 09:19:24 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 08 09:19:24 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 08 09:19:24 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 08 09:19:24 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 08 09:19:24 rivoplus systemd[1]: Started MPD Monitor Service. Dec 08 09:19:24 rivoplus sudo[29429]: pam_unix(sudo:session): session closed for user root Dec 08 09:19:24 rivoplus volumio[3105]: info: Successfully started MPD Monitor Dec 08 09:19:24 rivoplus mpd_monitor.sh[29435]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 09:19:25 rivoplus volumio[3105]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Dec 08 09:19:25 rivoplus volumio[3105]: info: FusionDsp - Stopping FusionDsp service Dec 08 09:19:25 rivoplus volumio[3105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 09:19:25 rivoplus volumio[3105]: TypeError: Cannot read property 'stop' of null Dec 08 09:19:25 rivoplus volumio[3105]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Dec 08 09:19:25 rivoplus volumio[3105]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 08 09:19:25 rivoplus volumio[3105]: at /volumio/app/pluginmanager.js:956:12 Dec 08 09:19:25 rivoplus volumio[3105]: at ChildProcess.exithandler (child_process.js:374:7) Dec 08 09:19:25 rivoplus volumio[3105]: at ChildProcess.emit (events.js:400:28) Dec 08 09:19:25 rivoplus volumio[3105]: at maybeClose (internal/child_process.js:1088:16) Dec 08 09:19:25 rivoplus volumio[3105]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Dec 08 09:19:25 rivoplus volumio[3105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 09:19:26 rivoplus sudo[29448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-08 09:18 Dec 08 09:19:26 rivoplus sudo[29448]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:44:55 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="af8df74278231e4f9fb9f04db56c320f"