-- Logs begin at Wed 2026-01-14 13:50:34 ACDT, end at Wed 2026-01-14 14:06:11 ACDT. -- Jan 14 14:05:00 rivoplus volumio[9723]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 14 14:05:00 rivoplus volumio[9723]: (node:9723) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 14 14:05:00 rivoplus volumio[9723]: (Use `node --trace-deprecation ...` to show where the warning was created) Jan 14 14:05:00 rivoplus volumio[9723]: info: Preparing to generate the ALSA configuration file Jan 14 14:05:00 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:05:00 rivoplus volumio[9723]: info: Stopping AccessToken refresher cron for QOBUZ Jan 14 14:05:00 rivoplus volumio[9723]: info: AccessToken refresher cron started for QOBUZ Jan 14 14:05:00 rivoplus volumio[9723]: info: Adding QOBUZ REST API Endpoints Jan 14 14:05:00 rivoplus volumio[9723]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 14 14:05:00 rivoplus volumio[9723]: info: [now-playing] App is listening on port 4004. Jan 14 14:05:00 rivoplus volumio[9723]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 14 14:05:00 rivoplus kernel: hdmitx: system: plugin Jan 14 14:05:00 rivoplus volumio[9723]: info: Discovery: adding 72f71f5e-f6ec-446b-b928-42920f530119 Jan 14 14:05:00 rivoplus volumio[9723]: info: Discovery: Found device Rivoplus Jan 14 14:05:00 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:00 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:00 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output for this device Jan 14 14:05:00 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:00 rivoplus sudo[10104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 14 14:05:00 rivoplus volumio[9723]: info: Adding audio output: Jan 14 14:05:00 rivoplus sudo[10104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:00 rivoplus volumio[9723]: info: Adding audio output: Jan 14 14:05:00 rivoplus volumio[9723]: info: Serial port opened successfully Jan 14 14:05:00 rivoplus volumio[9723]: info: Sending serial start messages Jan 14 14:05:00 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:00 rivoplus volumio[9723]: info: Reporting MCU Network Status: 2 Jan 14 14:05:00 rivoplus sudo[10104]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:05:00 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:05:00 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:05:00 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:00 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:05:00 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:05:00 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:00 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:05:00 rivoplus volumio[9723]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:05:00 rivoplus volumio[9723]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:05:00 rivoplus volumio[9723]: info: Reading ALSA contributions from plugins. Jan 14 14:05:00 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:05:00 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:05:00 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:05:00 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:05:00 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:05:00 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:05:00 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:05:00 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:05:00 rivoplus volumio[9723]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Jan 14 14:05:00 rivoplus volumio[9723]: error: Cannot start Volumio Streaming Daemon Jan 14 14:05:00 rivoplus volumio[9723]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 14 14:05:00 rivoplus volumio[9723]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 14 14:05:00 rivoplus volumio[9723]: info: Bluetooth name changed to Rivoplus Jan 14 14:05:00 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:00 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:00 rivoplus volumio[9723]: info: Upmpdcli Daemon Started Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:05:00 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:05:00 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:00 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:00 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:00 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:00 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:00 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:00 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 14 14:05:00 rivoplus volumio[9723]: info: Setting Device Volume Override Jan 14 14:05:01 rivoplus mpd[10016]: Jan 14 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 14:05:01 rivoplus systemd[1]: Started Music Player Daemon. Jan 14 14:05:01 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:01 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 14 14:05:01 rivoplus volumio[9723]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 14 14:05:01 rivoplus volumio[9723]: info: Disabling external Volume Control Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus sudo[9978]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus sudo[10000]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:01 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:01 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:01 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:01 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:01 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:01 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:01 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:01 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:01 rivoplus volumio[9723]: info: MP1 GPIO: Signalled system ready via GPIO Jan 14 14:05:01 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Jan 14 14:05:01 rivoplus volumio[9723]: info: Executing endpoint qc_getconfig Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.404 [10033.10033] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:05:01 rivoplus volumio[9723]: info: Volumio BT Module successfully started Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.419 [10033.10033] INFO VolumeManager: [0xab60d7d8]: Setting new playback volume: 75 Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.419 [10033.10033] INFO VolumeManager: [0xab60d7d8]: Setting new mute state: 0 Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.419 [10033.10033] INFO QobuzConnect: [0xab60e1a8]: Client initialized! Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.419 [10033.10033] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting Additional System Software info: Hardware Revision: 1.0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting HW Firmware info: undefined Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting HW Version info: 1.0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.451 [10033.10033] INFO LocalConfigManager: [0xab60d0b8]: Starting Local Configuration server Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.451 [10033.10033] INFO SampleApp: Starting Local configuration server Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.452 [10033.10033] INFO SampleApp: Connected to UNIX socket client 0xab602ed8 Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting HW Firmware info: 0.3.0 Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 14 14:05:01 rivoplus volumio[9723]: info: Setting HW Version info: 1.0 Jan 14 14:05:01 rivoplus volumio[9723]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 14 14:05:01 rivoplus volumio[9723]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:05:01 rivoplus volumio[9723]: info: Reporting Headphone State: false Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:01 rivoplus volumio[9723]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 14 14:05:01 rivoplus volumio[9723]: info: MCU Signalled Sleep Mode Disabled Jan 14 14:05:01 rivoplus volumio[9723]: info: Enabling Advanced system settings configuration Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jan 14 14:05:01 rivoplus volumio[9723]: info: Additional UI Settings Added for plugin music_service/inputs Jan 14 14:05:01 rivoplus volumio[9723]: info: MCU Signalled Auto Boot Mode On Power Active Jan 14 14:05:01 rivoplus sudo[10117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jan 14 14:05:01 rivoplus sudo[10117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:01 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Jan 14 14:05:01 rivoplus qobuz-connect[10033]: 20260114 14:05:01.554 [10033.10033] INFO SampleApp: Playback volume changed: 75 Jan 14 14:05:01 rivoplus sudo[10117]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:01 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 14 14:05:01 rivoplus volumio[9723]: 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: 12 Jan 14 14:05:01 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:05:01 rivoplus volumio[9723]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:05:01 rivoplus volumio[9723]: info: Volumio called home Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:01 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:02 rivoplus volumio[9723]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jan 14 14:05:02 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 14 14:05:02 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 14 Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:02 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:02 rivoplus volumio[9723]: info: TidalConnect service stoped! Jan 14 14:05:02 rivoplus sudo[10135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jan 14 14:05:02 rivoplus sudo[10135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:02 rivoplus sudo[10135]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:02 rivoplus volumio[9723]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 14 14:05:02 rivoplus volumio[9723]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 14 14:05:02 rivoplus volumio[9723]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 14 14:05:02 rivoplus volumio[9723]: info: camilladsp spawned new process with pid 10148, instance 1, run: true Jan 14 14:05:02 rivoplus volumio[9723]: info: camilladsp service started and running in background, instance 1 Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:02 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:02 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:02 rivoplus volumio[9723]: cannot open device 'volumioHw': Device or resource busy Jan 14 14:05:02 rivoplus volumio[9723]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 14 14:05:02 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:02 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:02 rivoplus volumio[9723]: cannot open device 'volumioHw': Device or resource busy Jan 14 14:05:02 rivoplus volumio[9723]: info: FusionDsp loaded Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:05:02 rivoplus sudo[10158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 14 14:05:02 rivoplus sudo[10158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:02 rivoplus volumio[9723]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 14 14:05:02 rivoplus volumio[9723]: info: Adding Signal Path Element [object Object] Jan 14 14:05:02 rivoplus volumio[9723]: info: Adding fusiondspeq DSP Signal Path Element Jan 14 14:05:02 rivoplus volumio[9723]: info: Additional DSP elements updated Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:02 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:02 rivoplus volumio[9723]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 14 14:05:02 rivoplus volumio[9723]: info: FusionDsp - ---- installed callbackRead Jan 14 14:05:02 rivoplus volumio[9723]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 14 14:05:02 rivoplus systemd[1]: Started FusionDsp Daemon. Jan 14 14:05:02 rivoplus sudo[10158]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:02 rivoplus volumio[9723]: info: Access Token successfully retrieved Jan 14 14:05:02 rivoplus volumio[9723]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:05:02 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:02 rivoplus volumio[9723]: error: The expression evaluated to a falsy value: Jan 14 14:05:02 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:02 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 14 14:05:02 rivoplus volumio[9723]: info: MPD running with PID10016 Jan 14 14:05:02 rivoplus volumio[9723]: ,establishing connection Jan 14 14:05:03 rivoplus volumio[9723]: info: Turning Off Onboard LEDs Jan 14 14:05:03 rivoplus sudo[10170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jan 14 14:05:03 rivoplus sudo[10170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus sudo[10170]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus volumio[9723]: error: updateQueue error: null Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 14 14:05:03 rivoplus volumio[9723]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 14 14:05:03 rivoplus volumio[9723]: info: Received Get System Info Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:05:03 rivoplus volumio[9723]: info: Discovery: Getting this device information Jan 14 14:05:03 rivoplus sudo[10173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jan 14 14:05:03 rivoplus sudo[10173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus sudo[10173]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus volumio[9723]: info: Listing playlists Jan 14 14:05:03 rivoplus volumio[9723]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 14 Jan 14 14:05:03 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 15 Jan 14 14:05:03 rivoplus volumio[9723]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:03 rivoplus volumio[9723]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:03 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:03 rivoplus sudo[10176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:05:03 rivoplus sudo[10176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:03 rivoplus sudo[10179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:03 rivoplus sudo[10179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 14 14:05:03 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:03 rivoplus sudo[10176]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:03 rivoplus sudo[10184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:03 rivoplus sudo[10184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:03 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:03 rivoplus volumio[9723]: info: Adding Inputs via Serial API Jan 14 14:05:03 rivoplus volumio[9723]: info: Adding Advanced Audio Settings via Serial API Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 14 14:05:03 rivoplus volumio[9723]: info: Additional UI Settings Added for plugin music_service/inputs Jan 14 14:05:03 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:03 rivoplus sudo[10179]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Found cast device: BRAVIA-4K-VH22-31ed147e649b22e93c72844a63fe3b15 Jan 14 14:05:03 rivoplus volumio[9723]: info: Adding audio output: Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:03 rivoplus sudo[10188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:03 rivoplus sudo[10188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:03 rivoplus volumio[9723]: error: Help! Some callbacks for volumioPushState are crashing! Jan 14 14:05:03 rivoplus volumio[9723]: error: Cannot read property 'sendVolumeMute' of undefined Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 14 14:05:03 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:03 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:03 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:03 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:03 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:03 rivoplus sudo[10184]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus sudo[10188]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:03 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:03 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:03 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:03 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:03 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jan 14 14:05:03 rivoplus volumio[9723]: info: Onboard LEDs initialized Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Getting audio outputs on start Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Requesting all other devices output Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::servicePushState Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:03 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:03 rivoplus volumio[9723]: error: Help! Some callbacks for volumioPushState are crashing! Jan 14 14:05:03 rivoplus volumio[9723]: error: Cannot read property 'sendVolumeMute' of undefined Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received inputs Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 14 14:05:03 rivoplus volumio[9723]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 14 14:05:03 rivoplus volumio[9723]: info: Received Get System Info Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:05:03 rivoplus volumio[9723]: info: Discovery: Getting this device information Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:05:03 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:03 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:03 rivoplus volumio[9723]: info: Listing playlists Jan 14 14:05:04 rivoplus volumio[9723]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 14 14:05:04 rivoplus volumio[9723]: error: updateQueue error: null Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:04 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:04 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 Jan 14 14:05:04 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:04 rivoplus volumio[9723]: Error adding Membership: Error: addMembership EINVAL Jan 14 14:05:04 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:04 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:04 rivoplus volumio[9723]: info: Asound.conf file unchanged, so no further update is needed Jan 14 14:05:04 rivoplus volumio[9723]: info: Output device has changed, restarting MPD Jan 14 14:05:04 rivoplus sudo[10201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:05:04 rivoplus sudo[10201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus volumio[9723]: info: Output device has changed, restarting Shairport Sync Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:04 rivoplus sudo[10201]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:04 rivoplus sudo[10203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:05:04 rivoplus sudo[10203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 14 14:05:04 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:05:04 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:05:04 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:04 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:04 rivoplus volumio[9723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:05:04 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:04 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:04 rivoplus volumio[9723]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:04 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:05:04 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:04 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:04 rivoplus volumio[9723]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:04 rivoplus volumio[9723]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jan 14 14:05:04 rivoplus volumio[9723]: info: Preparing to generate the ALSA configuration file Jan 14 14:05:04 rivoplus sudo[10214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:05:04 rivoplus sudo[10214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 14 14:05:04 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:04 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 14 14:05:04 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 14 14:05:04 rivoplus sudo[10214]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:04 rivoplus sudo[10210]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:05:04 rivoplus sudo[10210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus sudo[10210]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:04 rivoplus sudo[10221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:05:04 rivoplus sudo[10221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:04 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:04 rivoplus sudo[10221]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:04 rivoplus sudo[10225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:05:04 rivoplus sudo[10225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus qobuz-connect[10033]: 20260114 14:05:04.860 [10033.10033] INFO SampleApp: Stopping Local configuration server Jan 14 14:05:04 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:05:04 rivoplus qobuz-connect[10033]: 20260114 14:05:04.871 [10033.10033] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:05:04 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:05:04 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:05:04 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:05:04 rivoplus sudo[10225]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:04 rivoplus sudo[10229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:04 rivoplus sudo[10229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus volumio[9723]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:05:04 rivoplus volumio[9723]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:05:04 rivoplus volumio[9723]: info: Reading ALSA contributions from plugins. Jan 14 14:05:04 rivoplus sudo[10232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:04 rivoplus sudo[10232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:04 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:05:04 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:04 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:05:04 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:05:05 rivoplus volumio[9723]: info: Successfully retrieved User Session From TIDAL Jan 14 14:05:05 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:05] [connect] Successful connection Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:05 rivoplus volumio[9723]: info: Executing endpoint tc_getconfig Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 14 14:05:05 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 Jan 14 14:05:05 rivoplus volumio[9723]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 14 14:05:05 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:05 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:05:05 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:05 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: Not Reporting Auto name since its the default one Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:05 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:05 rivoplus volumio[9723]: info: Executing endpoint qc_getconfig Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.435 [10228.10228] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:05:05 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768361705 101 Jan 14 14:05:05 rivoplus volumio[9723]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 16 Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.444 [10228.10228] INFO VolumeManager: [0xab2a37d8]: Setting new playback volume: 75 Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.444 [10228.10228] INFO VolumeManager: [0xab2a37d8]: Setting new mute state: 0 Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.444 [10228.10228] INFO QobuzConnect: [0xab2a41a8]: Client initialized! Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.444 [10228.10228] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:05:05 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 17 Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.461 [10228.10228] INFO LocalConfigManager: [0xab2a30b8]: Starting Local Configuration server Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.461 [10228.10228] INFO SampleApp: Starting Local configuration server Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.462 [10228.10228] INFO SampleApp: Connected to UNIX socket client 0xab298ed8 Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:05 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:05 rivoplus qobuz-connect[10228]: 20260114 14:05:05.585 [10228.10228] INFO SampleApp: Playback volume changed: 75 Jan 14 14:05:06 rivoplus volumio[9723]: info: MCU Signalled Playback Inactive Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:05:06 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:05:06 rivoplus volumio[9723]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:05:06 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:06 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:06 rivoplus volumio[9723]: info: Successfully retrieved User Subscription From TIDAL Jan 14 14:05:06 rivoplus volumio[9723]: info: Adding TIDAL to Browse Sources Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 14:05:06 rivoplus volumio[9723]: info: [1768361706582] CoreMusicLibrary::Adding element TIDAL Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:05:06 rivoplus volumio[9723]: Cannot find translation for source TIDAL Jan 14 14:05:06 rivoplus sudo[10253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:05:06 rivoplus sudo[10253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:06 rivoplus volumio[9723]: info: Adding TIDAL REST API Endpoints Jan 14 14:05:06 rivoplus volumio[9723]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 14 14:05:06 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:06 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:06 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 17 Jan 14 14:05:06 rivoplus sudo[10253]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:06 rivoplus sudo[10255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:05:06 rivoplus sudo[10255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:06 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:05:06 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:06 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:05:06 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:05:06 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:05:06 rivoplus sudo[10255]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:07 rivoplus volumio[9723]: info: TidalConnect service started! Jan 14 14:05:07 rivoplus volumio[9723]: info: Completed starting Core Plugins Jan 14 14:05:07 rivoplus volumio[9723]: info: ------------------------------------------- Jan 14 14:05:07 rivoplus volumio[9723]: info: ----- MyVolumio plugins startup ---- Jan 14 14:05:07 rivoplus volumio[9723]: info: ------------------------------------------- Jan 14 14:05:07 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 14 14:05:07 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:07 rivoplus sudo[10268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:07 rivoplus sudo[10268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:07 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:07 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:07 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:07 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:07 rivoplus sudo[10268]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:07 rivoplus volumio[9723]: info: Executing endpoint metavolumio Jan 14 14:05:07 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 14 14:05:07 rivoplus mpd[10224]: Jan 14 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 14:05:07 rivoplus systemd[1]: Started Music Player Daemon. Jan 14 14:05:07 rivoplus sudo[10203]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:07 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:05:07 rivoplus sudo[10229]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:07 rivoplus sudo[10232]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:07 rivoplus volumio[9723]: info: Executing endpoint metavolumio Jan 14 14:05:07 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 14 14:05:07 rivoplus mpd_monitor.sh[10275]: MPD Monitor Service: Starting MPD Monitor Service Jan 14 14:05:07 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:07 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:07 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:07 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:07 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 17 Jan 14 14:05:07 rivoplus volumio[9723]: info: Executing endpoint metavolumio Jan 14 14:05:07 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 14 14:05:07 rivoplus volumio[9723]: info: Executing endpoint restartRAATSocket Jan 14 14:05:07 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:05:07 rivoplus volumio[9723]: info: Raat Daemon started successfully Jan 14 14:05:08 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:08 rivoplus volumio[9723]: info: TidalConnect service stoped! Jan 14 14:05:08 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:08 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:08 rivoplus volumio[9723]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:05:08 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:08 rivoplus volumio[9723]: error: The expression evaluated to a falsy value: Jan 14 14:05:08 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:08 rivoplus volumio[9723]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:05:08 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:08 rivoplus volumio[9723]: error: The expression evaluated to a falsy value: Jan 14 14:05:08 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:08 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:08 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 17 Jan 14 14:05:08 rivoplus volumio[9723]: error: updateQueue error: null Jan 14 14:05:08 rivoplus volumio[9723]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 14 14:05:08 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Jan 14 14:05:08 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:08 rivoplus volumio[9723]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:08 rivoplus volumio[9723]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:08 rivoplus volumio[9723]: info: Asound.conf file written Jan 14 14:05:08 rivoplus sudo[10289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:05:08 rivoplus sudo[10289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus sudo[10292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 14 14:05:08 rivoplus sudo[10292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 14 14:05:08 rivoplus sudo[10292]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:08 rivoplus sudo[10289]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:08 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Jan 14 14:05:08 rivoplus kernel: Err: can not set rate to 4270967296! Jan 14 14:05:08 rivoplus kernel: Range[3920000 - 500000000] Jan 14 14:05:08 rivoplus kernel: out of value, fixed it Jan 14 14:05:08 rivoplus kernel: id=0 set inskew=0 Jan 14 14:05:08 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:08 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:08 rivoplus volumio[9723]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jan 14 14:05:08 rivoplus volumio[9723]: Hardware is initialized using a generic method Jan 14 14:05:08 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jan 14 14:05:08 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jan 14 14:05:08 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jan 14 14:05:08 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jan 14 14:05:08 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Jan 14 14:05:08 rivoplus volumio[9723]: info: Output device has changed, restarting MPD Jan 14 14:05:08 rivoplus sudo[10310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:05:08 rivoplus sudo[10310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus volumio[9723]: info: Output device has changed, restarting Shairport Sync Jan 14 14:05:08 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:08 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:08 rivoplus sudo[10310]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:08 rivoplus sudo[10320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:05:08 rivoplus sudo[10320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 14 14:05:08 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:05:08 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:05:08 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:08 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:08 rivoplus volumio[9723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:05:08 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:08 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:08 rivoplus volumio[9723]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:05:08 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:08 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:05:08 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:08 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:08 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:08 rivoplus volumio[9723]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:05:08 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:08 rivoplus volumio[9723]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jan 14 14:05:08 rivoplus volumio[9723]: info: Preparing to generate the ALSA configuration file Jan 14 14:05:08 rivoplus sudo[10331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:05:08 rivoplus sudo[10331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus sudo[10326]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:05:08 rivoplus sudo[10326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:08 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 14 14:05:08 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:08 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 14 14:05:08 rivoplus sudo[10326]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:08 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 14 14:05:08 rivoplus sudo[10331]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus sudo[10338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:05:09 rivoplus sudo[10338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus volumio[9723]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:05:09 rivoplus volumio[9723]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:05:09 rivoplus volumio[9723]: info: Reading ALSA contributions from plugins. Jan 14 14:05:09 rivoplus sudo[10338]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:09 rivoplus sudo[10342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:09 rivoplus sudo[10342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus volumio[9723]: info: Executing endpoint tc_getconfig Jan 14 14:05:09 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 14 14:05:09 rivoplus qobuz-connect[10228]: 20260114 14:05:09.111 [10228.10228] INFO SampleApp: Stopping Local configuration server Jan 14 14:05:09 rivoplus volumio[9723]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 14 14:05:09 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:09 rivoplus qobuz-connect[10228]: 20260114 14:05:09.127 [10228.10228] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:05:09 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:05:09 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:09 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:05:09 rivoplus sudo[10342]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:05:09 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:09 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: Not Reporting Auto name since its the default one Jan 14 14:05:09 rivoplus volumio[9723]: info: Executing endpoint qc_getconfig Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.548 [10344.10344] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.557 [10344.10344] INFO VolumeManager: [0xacc777d8]: Setting new playback volume: 75 Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.557 [10344.10344] INFO VolumeManager: [0xacc777d8]: Setting new mute state: 0 Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.557 [10344.10344] INFO QobuzConnect: [0xacc781a8]: Client initialized! Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.557 [10344.10344] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus volumio[9723]: info: RAAT: Requesting Headphone Status Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.590 [10344.10344] INFO LocalConfigManager: [0xacc770b8]: Starting Local Configuration server Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.590 [10344.10344] INFO SampleApp: Starting Local configuration server Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.591 [10344.10344] INFO SampleApp: Connected to UNIX socket client 0xacc6ced8 Jan 14 14:05:09 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:05:09 rivoplus volumio[9723]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:05:09 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:05:09 rivoplus sudo[10360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:09 rivoplus sudo[10360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:09 rivoplus qobuz-connect[10344]: 20260114 14:05:09.699 [10344.10344] INFO SampleApp: Playback volume changed: 75 Jan 14 14:05:09 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:09 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:09 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:09 rivoplus sudo[10360]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:09 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:09 rivoplus volumio[9723]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:05:09 rivoplus volumio[9723]: info: Reporting Headphone State: false Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:09 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:09 rivoplus volumio[9723]: info: Checking for updated MCU Firmware Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:05:09 rivoplus volumio[9723]: info: Firware on device is on latest version, no need to update Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:09 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:09 rivoplus sudo[10367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:05:09 rivoplus sudo[10367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:09 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus sudo[10367]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:09 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:09 rivoplus volumio[9723]: info: Asound.conf file written Jan 14 14:05:09 rivoplus sudo[10370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:05:09 rivoplus sudo[10370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:05:09 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:09 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:05:09 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:05:09 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:05:09 rivoplus sudo[10370]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:09 rivoplus sudo[10373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 14 14:05:09 rivoplus sudo[10373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:09 rivoplus sudo[10373]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Jan 14 14:05:10 rivoplus kernel: Err: can not set rate to 4266967296! Jan 14 14:05:10 rivoplus kernel: Range[3920000 - 500000000] Jan 14 14:05:10 rivoplus kernel: out of value, fixed it Jan 14 14:05:10 rivoplus kernel: id=0 set inskew=0 Jan 14 14:05:10 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:10 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:10 rivoplus volumio[9723]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jan 14 14:05:10 rivoplus volumio[9723]: Hardware is initialized using a generic method Jan 14 14:05:10 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jan 14 14:05:10 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jan 14 14:05:10 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jan 14 14:05:10 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jan 14 14:05:10 rivoplus volumio[9723]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Jan 14 14:05:10 rivoplus volumio[9723]: info: Output device has changed, restarting MPD Jan 14 14:05:10 rivoplus sudo[10388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:05:10 rivoplus sudo[10388]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus sudo[10388]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus volumio[9723]: info: Output device has changed, restarting Shairport Sync Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:10 rivoplus sudo[10391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:05:10 rivoplus sudo[10391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:10 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:10 rivoplus volumio[9723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:05:10 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:10 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:10 rivoplus volumio[9723]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:10 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:05:10 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:05:10 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:05:10 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:10 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:10 rivoplus volumio[9723]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jan 14 14:05:10 rivoplus volumio[9723]: info: Preparing to generate the ALSA configuration file Jan 14 14:05:10 rivoplus sudo[10401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:05:10 rivoplus sudo[10401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus sudo[10397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:05:10 rivoplus sudo[10397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus sudo[10397]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus sudo[10401]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus volumio[9723]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:05:10 rivoplus volumio[9723]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:05:10 rivoplus volumio[9723]: info: Reading ALSA contributions from plugins. Jan 14 14:05:10 rivoplus volumio[9723]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:05:10 rivoplus sudo[10410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:05:10 rivoplus sudo[10410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus sudo[10410]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:10 rivoplus sudo[10413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:05:10 rivoplus sudo[10413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:10 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:10 rivoplus qobuz-connect[10344]: 20260114 14:05:10.603 [10344.10344] INFO SampleApp: Stopping Local configuration server Jan 14 14:05:10 rivoplus volumio[9723]: info: Executing endpoint restartRAATSocket Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:05:10 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:05:10 rivoplus volumio[9723]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 14 14:05:10 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:10 rivoplus volumio[9723]: info: Raat Daemon started successfully Jan 14 14:05:10 rivoplus qobuz-connect[10344]: 20260114 14:05:10.618 [10344.10344] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:05:10 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:10 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:05:10 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:05:10 rivoplus sudo[10413]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:10 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:05:10 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:10 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:10 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:10 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:10 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:05:10 rivoplus volumio[9723]: info: Not Reporting Auto name since its the default one Jan 14 14:05:10 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jan 14 14:05:10 rivoplus volumio[9723]: info: Executing endpoint qc_getconfig Jan 14 14:05:10 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.965 [10417.10417] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.972 [10417.10417] INFO VolumeManager: [0xacd1c7d8]: Setting new playback volume: 75 Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.972 [10417.10417] INFO VolumeManager: [0xacd1c7d8]: Setting new mute state: 0 Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.972 [10417.10417] INFO QobuzConnect: [0xacd1d1a8]: Client initialized! Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.973 [10417.10417] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:05:10 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 19 Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.989 [10417.10417] INFO LocalConfigManager: [0xacd1c0b8]: Starting Local Configuration server Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.990 [10417.10417] INFO SampleApp: Starting Local configuration server Jan 14 14:05:10 rivoplus qobuz-connect[10417]: 20260114 14:05:10.990 [10417.10417] INFO SampleApp: Connected to UNIX socket client 0xacd11ed8 Jan 14 14:05:10 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:05:10 rivoplus volumio[9723]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:05:11 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:11 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:05:11 rivoplus sudo[10433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:11 rivoplus sudo[10433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:11 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:11 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:11 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:11 rivoplus qobuz-connect[10417]: 20260114 14:05:11.115 [10417.10417] INFO SampleApp: Playback volume changed: 75 Jan 14 14:05:11 rivoplus sudo[10433]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:11 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:11 rivoplus sudo[10439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:05:11 rivoplus sudo[10439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus volumio[9723]: info: Asound.conf file unchanged, so no further update is needed Jan 14 14:05:11 rivoplus volumio[9723]: info: Output device has changed, restarting MPD Jan 14 14:05:11 rivoplus sudo[10439]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus sudo[10444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:11 rivoplus sudo[10445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:05:11 rivoplus sudo[10444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus sudo[10445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus volumio[9723]: info: Output device has changed, restarting Shairport Sync Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:11 rivoplus sudo[10449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:05:11 rivoplus sudo[10449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus sudo[10449]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:05:11 rivoplus sudo[10451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:05:11 rivoplus sudo[10451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:05:11 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:11 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:05:11 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:05:11 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:11 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:05:11 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:05:11 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:11 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:11 rivoplus volumio[9723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:05:11 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:11 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:11 rivoplus volumio[9723]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:05:11 rivoplus sudo[10445]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:11 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:11 rivoplus volumio[9723]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:11 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:05:11 rivoplus volumio[9723]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jan 14 14:05:11 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:05:11 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:05:11 rivoplus sudo[10470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:05:11 rivoplus sudo[10470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus sudo[10470]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus sudo[10464]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:05:11 rivoplus sudo[10464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus sudo[10464]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus sudo[10479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:05:11 rivoplus sudo[10479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:11 rivoplus sudo[10479]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:11 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:11 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:11 rivoplus volumio[9723]: info: Shairport-Sync Started Jan 14 14:05:11 rivoplus volumio[9723]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 14 14:05:11 rivoplus sudo[10482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:05:11 rivoplus volumio[9723]: info: MPD Permissions set Jan 14 14:05:11 rivoplus volumio[9723]: info: TidalConnect service started! Jan 14 14:05:11 rivoplus volumio[9723]: info: Raat Daemon started successfully Jan 14 14:05:11 rivoplus sudo[10482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:11 rivoplus volumio[9723]: info: Executing endpoint restartRAATSocket Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:05:11 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:11 rivoplus qobuz-connect[10417]: 20260114 14:05:11.842 [10417.10417] INFO SampleApp: Stopping Local configuration server Jan 14 14:05:11 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:05:11 rivoplus qobuz-connect[10417]: 20260114 14:05:11.853 [10417.10417] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:05:11 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:05:11 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:05:11 rivoplus sudo[10482]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:11 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:11 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:11 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:11 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:05:11 rivoplus volumio[9723]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:05:11 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:11 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: Not Reporting Auto name since its the default one Jan 14 14:05:12 rivoplus volumio[9723]: info: TidalConnect service stoped! Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: info: Executing endpoint qc_getconfig Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.164 [10486.10486] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 19 Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.171 [10486.10486] INFO VolumeManager: [0xabcfe7d8]: Setting new playback volume: 75 Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.171 [10486.10486] INFO VolumeManager: [0xabcfe7d8]: Setting new mute state: 0 Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.172 [10486.10486] INFO QobuzConnect: [0xabcff1a8]: Client initialized! Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.172 [10486.10486] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:05:12 rivoplus volumio[9723]: info: Starting Shairport Sync Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.187 [10486.10486] INFO LocalConfigManager: [0xabcfe0b8]: Starting Local Configuration server Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.187 [10486.10486] INFO SampleApp: Starting Local configuration server Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.188 [10486.10486] INFO SampleApp: Connected to UNIX socket client 0xabcf3ed8 Jan 14 14:05:12 rivoplus volumio[9723]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:05:12 rivoplus volumio[9723]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:05:12 rivoplus sudo[10506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:05:12 rivoplus sudo[10506]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:12 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:05:12 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:05:12 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:12 rivoplus systemd[1]: shairport-sync.service: Start request repeated too quickly. Jan 14 14:05:12 rivoplus systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Jan 14 14:05:12 rivoplus systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Jan 14 14:05:12 rivoplus sudo[10506]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:12 rivoplus qobuz-connect[10486]: 20260114 14:05:12.314 [10486.10486] INFO SampleApp: Playback volume changed: 75 Jan 14 14:05:12 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:12 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:12 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:12 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 19 Jan 14 14:05:12 rivoplus sudo[10515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:05:12 rivoplus sudo[10515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:12 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:12 rivoplus volumio[9723]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:12 rivoplus volumio[9723]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:12 rivoplus sudo[10515]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:12 rivoplus volumio[9723]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jan 14 14:05:12 rivoplus volumio[9723]: Job for shairport-sync.service failed. Jan 14 14:05:12 rivoplus volumio[9723]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Jan 14 14:05:12 rivoplus sudo[10518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:05:12 rivoplus sudo[10518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus sudo[10519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:05:12 rivoplus sudo[10519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:12 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:12 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:05:12 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:05:12 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:12 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus sudo[10518]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:12 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: info: Raat Daemon started successfully Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus sudo[10519]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: info: Executing endpoint restartRAATSocket Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Jan 14 14:05:12 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:12 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Jan 14 14:05:12 rivoplus volumio[9723]: info: RAAT: Requesting Headphone Status Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:05:12 rivoplus volumio[9723]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:05:12 rivoplus volumio[9723]: info: Reporting Headphone State: false Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:12 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:13 rivoplus volumio[9723]: info: Executing endpoint tc_getconfig Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 14 14:05:13 rivoplus vtcs[10523]: STARTING TidalConnect services, version: 1.5.2.56 Jan 14 14:05:13 rivoplus vtcs[10523]: STARTED TidalConnect services. Jan 14 14:05:13 rivoplus volumio[9723]: info: Executing endpoint tc_connect Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 14 14:05:13 rivoplus volumio[9723]: info: Connecting to TidalConnect Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::servicePushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:13 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::servicePushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreStateMachine::pushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::volumioPushState Jan 14 14:05:13 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:05:13 rivoplus volumio[9723]: info: MRS: Pushing multiroomSync output Jan 14 14:05:13 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:13 rivoplus volumio[9723]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jan 14 14:05:13 rivoplus volumio[9723]: info: TidalConnect service stoped! Jan 14 14:05:13 rivoplus volumio[9723]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:13 rivoplus volumio[9723]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:13 rivoplus sudo[10561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:05:13 rivoplus sudo[10561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:13 rivoplus sudo[10561]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:13 rivoplus volumio[9723]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:05:13 rivoplus volumio[9723]: info: FusionDsp - Stopping FusionDsp service Jan 14 14:05:13 rivoplus volumio[9723]: info: camilladsp stopping service pid 10148... Jan 14 14:05:14 rivoplus volumio[9723]: info: camilladsp service terminated, instance 1 Jan 14 14:05:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:14.043+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:14.051+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:14 rivoplus sudo[10567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jan 14 14:05:14 rivoplus sudo[10567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:14 rivoplus volumio[10160]: Backend configuration: Jan 14 14:05:14 rivoplus volumio[10160]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 14 14:05:14 rivoplus volumio[10160]: active_config_txt: null Jan 14 14:05:14 rivoplus volumio[10160]: camilla_host: 127.0.0.1 Jan 14 14:05:14 rivoplus volumio[10160]: camilla_port: 9876 Jan 14 14:05:14 rivoplus volumio[10160]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jan 14 14:05:14 rivoplus volumio[10160]: config_dir: /data/configuration/audio_interface/fusiondsp Jan 14 14:05:14 rivoplus volumio[10160]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 14 14:05:14 rivoplus volumio[10160]: log_file: /tmp/camilladsp.log Jan 14 14:05:14 rivoplus volumio[10160]: on_get_active_config: null Jan 14 14:05:14 rivoplus volumio[10160]: on_set_active_config: null Jan 14 14:05:14 rivoplus volumio[10160]: port: 5011 Jan 14 14:05:14 rivoplus volumio[10160]: supported_capture_types: null Jan 14 14:05:14 rivoplus volumio[10160]: supported_playback_types: null Jan 14 14:05:14 rivoplus volumio[10160]: update_config_symlink: false Jan 14 14:05:14 rivoplus volumio[10160]: update_config_txt: false Jan 14 14:05:14 rivoplus volumio[10160]: ======== Running on http://0.0.0.0:5011 ======== Jan 14 14:05:14 rivoplus volumio[10160]: (Press CTRL+C to quit) Jan 14 14:05:14 rivoplus systemd[1]: Stopping FusionDsp Daemon... Jan 14 14:05:14 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:14 rivoplus volumio[9723]: info: RAAT: Requesting Headphone Status Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:05:14 rivoplus volumio[9723]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:05:14 rivoplus volumio[9723]: info: Reporting Headphone State: false Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:14 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:14 rivoplus mpd[10483]: Jan 14 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 14:05:14 rivoplus systemd[1]: Started Music Player Daemon. Jan 14 14:05:14 rivoplus sudo[10320]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:14 rivoplus sudo[10451]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:14 rivoplus sudo[10391]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:14 rivoplus systemd[1]: fusiondsp.service: Succeeded. Jan 14 14:05:14 rivoplus systemd[1]: Stopped FusionDsp Daemon. Jan 14 14:05:14 rivoplus sudo[10567]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:14 rivoplus volumio[9723]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:05:14 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:14 rivoplus volumio[9723]: error: The expression evaluated to a falsy value: Jan 14 14:05:14 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:14 rivoplus volumio[9723]: info: FusionDsp - Reporting Fusion DSP Disabled Jan 14 14:05:14 rivoplus volumio[9723]: info: Removing fusiondspeq DSP Signal Path Element Jan 14 14:05:14 rivoplus volumio[9723]: info: Additional DSP elements updated Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:14 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:14 rivoplus volumio[9723]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:05:14 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:14 rivoplus volumio[9723]: error: The expression evaluated to a falsy value: Jan 14 14:05:14 rivoplus volumio[9723]: assert.ok(self.idling) Jan 14 14:05:14 rivoplus volumio[9723]: error: updateQueue error: null Jan 14 14:05:14 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:05:14 rivoplus sudo[10444]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:14 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:14 rivoplus mpd_monitor.sh[10573]: MPD Monitor Service: Starting MPD Monitor Service Jan 14 14:05:14 rivoplus volumio[9723]: info: TidalConnect service stoped! Jan 14 14:05:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:14.782+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:14 rivoplus volumio[9723]: info: Check plugin dependencies Jan 14 14:05:14 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:05:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:14.939+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:14 rivoplus volumio[9723]: info: Rename folder Jan 14 14:05:14 rivoplus volumio[9723]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:15 rivoplus volumio[9723]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:05:15 rivoplus volumio[9723]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 14:05:15 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:15.077+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:15 rivoplus volumio[9723]: info: Move to category Jan 14 14:05:15 rivoplus sudo[10592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:05:15 rivoplus sudo[10592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:15 rivoplus sudo[10592]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:15 rivoplus volumio[9723]: info: RAAT: Requesting Headphone Status Jan 14 14:05:15 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:05:15 rivoplus volumio[9723]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:05:15 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:05:15 rivoplus volumio[9723]: info: Reporting Headphone State: false Jan 14 14:05:15 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:15 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:15 rivoplus volumio[9723]: info: Updating RAAT Signal Path Jan 14 14:05:15 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:15.737+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:15 rivoplus volumio[9723]: info: Checking if install.sh is present Jan 14 14:05:15 rivoplus volumio[9723]: info: Executing install.sh Jan 14 14:05:15 rivoplus volumio[9723]: info: TidalConnect service started! Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 14 14:05:15 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 14 14:05:15 rivoplus sudo[10601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jan 14 14:05:15 rivoplus sudo[10601]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:16 rivoplus sudo[10622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Jan 14 14:05:16 rivoplus sudo[10622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:17 rivoplus volumio[9723]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 14 14:05:17 rivoplus volumio[9723]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 14 14:05:17 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:17 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:17 rivoplus volumio[9723]: info: Starting MyVolumio Remote Streaming Endpoints Jan 14 14:05:17 rivoplus volumio[9723]: info: MyVolumio login type: Token Jan 14 14:05:17 rivoplus volumio[9723]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 14 14:05:17 rivoplus volumio[9723]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jan 14 14:05:17 rivoplus sudo[10661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:17 rivoplus sudo[10661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:17 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:05:17 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:05:17 rivoplus sudo[10677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:17 rivoplus sudo[10677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:17 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:05:17 rivoplus sudo[10661]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:17 rivoplus mpd_monitor.sh[10684]: MPD Monitor Service: Starting MPD Monitor Service Jan 14 14:05:17 rivoplus sudo[10689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:05:17 rivoplus sudo[10689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:17 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:05:17 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:17 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:05:17 rivoplus sudo[10677]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:17 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:17 rivoplus volumio[9723]: info: TidalConnect service started! Jan 14 14:05:17 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:17 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:05:17 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:05:17 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:05:17 rivoplus sudo[10689]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:17 rivoplus mpd_monitor.sh[10703]: MPD Monitor Service: Starting MPD Monitor Service Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:17 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:17 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:17 rivoplus volumio[9723]: info: Successfully started MPD Monitor Jan 14 14:05:18 rivoplus volumio[9723]: info: TidalConnect service started! Jan 14 14:05:19 rivoplus volumio[9723]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 14 14:05:19 rivoplus volumio[9723]: info: MyVolumio token set successfully Jan 14 14:05:19 rivoplus volumio[9723]: info: MYVOLUMIO: Adding device Jan 14 14:05:19 rivoplus volumio[9723]: info: MYVOLUMIO: Evaluating Server Jan 14 14:05:21 rivoplus volumio[9723]: info: MyVolumio status changed Jan 14 14:05:21 rivoplus volumio[9723]: info: Streaming services startup Jan 14 14:05:21 rivoplus volumio[9723]: info: Starting Streaming Daemon Jan 14 14:05:21 rivoplus volumio[9723]: info: Removing browser output: myVolumio user plan is not superstar Jan 14 14:05:21 rivoplus volumio[9723]: info: Removing audio output: Jan 14 14:05:21 rivoplus volumio[9723]: info: Stoppping Tunnel 1 Jan 14 14:05:21 rivoplus sudo[10905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 14 14:05:21 rivoplus sudo[10905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:21 rivoplus systemd[1]: Starting Cleanup of Temporary Directories... Jan 14 14:05:21 rivoplus sudo[10905]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:21 rivoplus sudo[10910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 14 14:05:21 rivoplus sudo[10910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:21 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:21 rivoplus volumio[9723]: error: Cannot start Volumio Streaming Daemon Jan 14 14:05:21 rivoplus volumio[9723]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 14 14:05:21 rivoplus volumio[9723]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 14 14:05:21 rivoplus systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jan 14 14:05:21 rivoplus volumio[9723]: info: Setting Geolocation for MyVolumio to au1 Jan 14 14:05:21 rivoplus systemd[1]: Started Cleanup of Temporary Directories. Jan 14 14:05:21 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:21 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:21 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:21 rivoplus sudo[10910]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:21 rivoplus volumio[9723]: info: Remote SSH Stopped Jan 14 14:05:22 rivoplus volumio[9723]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 14 14:05:22 rivoplus volumio[9723]: info: Updating MyVolumio device info Jan 14 14:05:22 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:23 rivoplus volumio[9723]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 14 14:05:24 rivoplus volumio[9723]: info: Reading package lists... Jan 14 14:05:24 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:24.374+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:25 rivoplus volumio[9723]: info: Building dependency tree... Jan 14 14:05:25 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:25.306+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:25 rivoplus volumio[9723]: info: Reading state information... Jan 14 14:05:25 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:25.332+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:25 rivoplus volumio[9723]: info: All packages are up to date. Jan 14 14:05:25 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:25.404+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:25 rivoplus sudo[10622]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:25 rivoplus sudo[10914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jan 14 14:05:25 rivoplus sudo[10914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:25 rivoplus volumio[9723]: info: Reading package lists... Jan 14 14:05:25 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:25.659+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:26 rivoplus volumio[9723]: info: MYVOLUMIO: Adding device Jan 14 14:05:26 rivoplus volumio[9723]: info: MYVOLUMIO: Evaluating Server Jan 14 14:05:27 rivoplus volumio[9723]: error: MyVolumio Plugin failed to start in a timely fashion Jan 14 14:05:27 rivoplus volumio[9723]: [Metrics] CommandRouter: 52s 138.15ms Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::volumiosetStartupVolume Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::Close All Modals sent Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::Close All Modals sent Jan 14 14:05:27 rivoplus volumio[9723]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 14 14:05:27 rivoplus volumio[9723]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 14 14:05:27 rivoplus volumio[9723]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Jan 14 14:05:27 rivoplus volumio[9723]: info: Building dependency tree... Jan 14 14:05:27 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:27.827+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:27 rivoplus volumio[9723]: info: Reading state information... Jan 14 14:05:27 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:27.836+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:27 rivoplus volumio[9723]: info: Setting Geolocation for MyVolumio to au1 Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:27 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:28 rivoplus volumio[9723]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 14 14:05:28 rivoplus volumio[9723]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jan 14 14:05:28 rivoplus sudo[10914]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:28 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:28.433+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:28 rivoplus volumio[9723]: info: python3-pip is already the newest version (18.1-5). Jan 14 14:05:28 rivoplus volumio[9723]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jan 14 14:05:28 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:28.451+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:28 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:28.454+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:28 rivoplus sudo[10947]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jan 14 14:05:28 rivoplus sudo[10947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:28 rivoplus volumio[9723]: info: lib/systemd/system/fusiondsp.service Jan 14 14:05:28 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:28.497+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:28 rivoplus sudo[10947]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:28 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 14 14:05:28 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 14 14:05:28 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:28 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:28 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 14 14:05:28 rivoplus volumio[9723]: info: Updating MyVolumio device info Jan 14 14:05:28 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:29 rivoplus volumio[9723]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 14 14:05:29 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:05:29 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:05:29 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 14 14:05:29 rivoplus volumio-remote-updater[2831]: No test mode Jan 14 14:05:29 rivoplus volumio-remote-updater[2831]: No alpha test mode Jan 14 14:05:29 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 14 14:05:30 rivoplus volumio[9723]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jan 14 14:05:30 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:30.563+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:30 rivoplus volumio[9723]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jan 14 14:05:30 rivoplus volumio[9723]: info: Jan 14 14:05:30 rivoplus volumio[9723]: info: cgui-1.0.0.zip opened Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/LICENSE.txt Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/README.md Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/build/ Jan 14 14:05:30 rivoplus sudo[10973]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jan 14 14:05:30 rivoplus sudo[10973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/robots.txt Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/logo512.png Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/asset-manifest.json Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/index.html Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/.put_statics_here Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/favicon.ico Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/logo192.png Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/manifest.json Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/css-variables.css Jan 14 14:05:30 rivoplus sudo[10973]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/service-worker.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/build/static/ Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/build/static/css/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/build/static/media/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/build/static/js/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.71e11955.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jan 14 14:05:30 rivoplus sudo[10975]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jan 14 14:05:30 rivoplus sudo[10975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jan 14 14:05:30 rivoplus sudo[10975]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/config/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/config/camillagui.yml Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/config/gui-config.yml Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/backend/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/filters_test.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/version.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/filters.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/settings.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/views.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/filterdefaults.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__init__.py Jan 14 14:05:30 rivoplus volumio[9723]: info: creating directory: cgui/backend/__pycache__/ Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/routes.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/backend/filemanagement.py Jan 14 14:05:30 rivoplus volumio[9723]: info: extracting: cgui/main.py Jan 14 14:05:30 rivoplus sudo[10977]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jan 14 14:05:30 rivoplus sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:30 rivoplus sudo[10977]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:30 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:05:30 rivoplus kernel: hdmitx: system: plugout Jan 14 14:05:30 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.076+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.078+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.079+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.080+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.081+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.083+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.084+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.084+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.086+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.087+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.088+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.089+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.089+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.090+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.091+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.092+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.105+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.106+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.106+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.107+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.107+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.108+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.108+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.109+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.110+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.110+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.111+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.112+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.112+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.113+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.113+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.114+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.114+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.115+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.116+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.117+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.119+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.120+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.122+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.124+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.126+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.127+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.129+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.131+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.132+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.134+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.136+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.137+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.139+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.140+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.141+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.142+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.143+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.145+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.146+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.147+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.148+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.149+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.150+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:31.152+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:31 rivoplus volumio[9723]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 14 14:05:31 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 14 14:05:32 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:05:32 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 14 14:05:32 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 14 14:05:32 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetState Jan 14 14:05:32 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:32 rivoplus kernel: hdmitx: system: plugin Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:05:32 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:05:32 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:05:32 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:32 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:05:32 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:05:32 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:32 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:05:32 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:05:32 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:05:32 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:05:32 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:05:32 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:05:32 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:05:32 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:05:32 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:05:32 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:05:32 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:32 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:32 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:32 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:32 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:32 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:33 rivoplus volumio[9723]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:05:33 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:33.993+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:05:34 rivoplus volumio[9723]: info: BOOT COMPLETED Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:05:35 rivoplus volumio[9723]: info: Not Reporting Auto name since its the default one Jan 14 14:05:35 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:05:35 rivoplus volumio[9723]: No protocol specified Jan 14 14:05:35 rivoplus volumio[9723]: xcb_connection_has_error() returned true Jan 14 14:05:36 rivoplus sudo[10991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:05:36 rivoplus sudo[10991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:36 rivoplus sudo[10991]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:36 rivoplus sudo[10993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:05:36 rivoplus sudo[10993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:36 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:05:36 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:05:36 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:05:36 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:05:36 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:05:36 rivoplus volumio[9723]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:05:36 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:05:36 rivoplus sudo[10993]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:36 rivoplus volumio[9723]: info: Raat Daemon started successfully Jan 14 14:05:36 rivoplus volumio[9723]: info: Executing endpoint restartRAATSocket Jan 14 14:05:36 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:05:36 rivoplus volumio[9723]: info: CorePlayQueue::getTrack 0 Jan 14 14:05:36 rivoplus volumio[9723]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::volumioGetBrowseSources Jan 14 14:05:37 rivoplus volumio[9723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:05:37 rivoplus volumio[9723]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:05:37 rivoplus volumio[9723]: info: FusionDsp - Stopping FusionDsp service Jan 14 14:05:37 rivoplus volumio[9723]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:05:37 rivoplus volumio[9723]: TypeError: Cannot read property 'stop' of null Jan 14 14:05:37 rivoplus volumio[9723]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jan 14 14:05:37 rivoplus volumio[9723]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 14 14:05:37 rivoplus volumio[9723]: at /volumio/app/pluginmanager.js:956:12 Jan 14 14:05:37 rivoplus volumio[9723]: at ChildProcess.exithandler (child_process.js:374:7) Jan 14 14:05:37 rivoplus volumio[9723]: at ChildProcess.emit (events.js:400:28) Jan 14 14:05:37 rivoplus volumio[9723]: at maybeClose (internal/child_process.js:1088:16) Jan 14 14:05:37 rivoplus volumio[9723]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Jan 14 14:05:37 rivoplus volumio[9723]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:05:38 rivoplus sudo[11030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-14 14:04 Jan 14 14:05:38 rivoplus sudo[11030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:38 rivoplus sudo[11030]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:39 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 14 14:05:39 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 14 14:05:39 rivoplus vtcs[10523]: [2026-01-14 14:05:39.005] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 14 14:05:39 rivoplus qobuz-connect[10486]: 20260114 14:05:39.009 [10486.10486] INFO SampleApp: Socket closed Jan 14 14:05:39 rivoplus systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 14 14:05:39 rivoplus sudo[10073]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:39 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:39.008+10:30 level=ERROR msg="failed reading message" component=volumio/socket error="read tcp 127.0.0.1:53008->127.0.0.1:3000: read: connection reset by peer" Jan 14 14:05:39 rivoplus sudo[10601]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:39 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:39.028+10:30 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Jan 14 14:05:39 rivoplus systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 14 14:05:39 rivoplus systemd[1]: Started dynamicswap service. Jan 14 14:05:39 rivoplus systemd[1]: dynamicswap.service: Succeeded. Jan 14 14:05:39 rivoplus systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 14 14:05:39 rivoplus systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jan 14 14:05:39 rivoplus systemd[1]: Started dynamicswap service. Jan 14 14:05:39 rivoplus systemd[1]: Stopped Volumio Backend Module. Jan 14 14:05:39 rivoplus systemd[1]: Started Volumio Backend Module. Jan 14 14:05:39 rivoplus systemd[1]: dynamicswap.service: Succeeded. Jan 14 14:05:40 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:40.037+10:30 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Jan 14 14:05:41 rivoplus volumio[11046]: info: ------------------------------------------- Jan 14 14:05:41 rivoplus volumio[11046]: info: ----- Volumio3 ---- Jan 14 14:05:41 rivoplus volumio[11046]: info: ------------------------------------------- Jan 14 14:05:41 rivoplus volumio[11046]: info: ----- System startup ---- Jan 14 14:05:41 rivoplus volumio[11046]: info: ------------------------------------------- Jan 14 14:05:42 rivoplus volumio[11046]: info: MYVOLUMIO Environment detected Jan 14 14:05:42 rivoplus volumio[11046]: info: Plugin folders cleanup Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning into folder /volumio/app/plugins/ Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category audio_interface Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category miscellanea Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category music_service Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category plugins.json Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category system_controller Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category user_interface Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning into folder /data/plugins/ Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category audio_interface Jan 14 14:05:42 rivoplus volumio[11046]: info: Scanning category user_interface Jan 14 14:05:42 rivoplus volumio[11046]: info: Plugin folders cleanup completed Jan 14 14:05:42 rivoplus volumio[11046]: info: ------------------------------------------- Jan 14 14:05:42 rivoplus volumio[11046]: info: ----- Core plugins startup ---- Jan 14 14:05:42 rivoplus volumio[11046]: info: ------------------------------------------- Jan 14 14:05:42 rivoplus volumio[11046]: info: Loading plugins from folder /volumio/app/plugins/ Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin bluetooth to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin multiroom to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin upnp to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin metavolumio to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin cd_controller to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin raat to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 14 14:05:42 rivoplus volumio[11046]: info: Loading plugins from folder /data/plugins/ Jan 14 14:05:42 rivoplus volumio[11046]: info: Loading plugin "system"... Jan 14 14:05:42 rivoplus volumio[11046]: info: Loading plugin "appearance"... Jan 14 14:05:44 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:44] [connect] Successful connection Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "network"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Refreshing Cached IP Addresses Jan 14 14:05:44 rivoplus sudo[11131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 14 14:05:44 rivoplus sudo[11131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:44 rivoplus sudo[11133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 14 14:05:44 rivoplus sudo[11133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "services"... Jan 14 14:05:44 rivoplus sudo[11133]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:44 rivoplus sudo[11131]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "volumio5onboarding"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "alsa_controller"... Jan 14 14:05:44 rivoplus sudo[11145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 14 14:05:44 rivoplus sudo[11145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:44 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:44 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:44 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:44 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "wizard"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "networkfs"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Starting Udev Watcher for removable devices Jan 14 14:05:44 rivoplus volumio[11046]: info: Ignoring mount for partition: BOOT Jan 14 14:05:44 rivoplus volumio[11046]: info: Ignoring mount for partition: volumio Jan 14 14:05:44 rivoplus volumio[11046]: info: Ignoring mount for partition: volumio_data Jan 14 14:05:44 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "volumio_command_line_client"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "upnp"... Jan 14 14:05:44 rivoplus volumio[11046]: info: [1768361744651] Starting Upmpd Daemon Jan 14 14:05:44 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "my_music"... Jan 14 14:05:44 rivoplus volumio[11046]: info: Loading plugin "mpd"... Jan 14 14:05:44 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:44 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus volumio[11046]: info: Loading plugin "upnp_browser"... Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus volumio[11046]: info: Starting UPNP Browser Jan 14 14:05:45 rivoplus volumio[11046]: info: Loading plugin "bluetooth"... Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus volumio[11046]: info: [1768361745778] Starting BluetoothController Jan 14 14:05:45 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:45 rivoplus volumio[11046]: info: Loading plugin "alarm-clock"... Jan 14 14:05:46 rivoplus volumio[11046]: info: Loading plugin "manifestui"... Jan 14 14:05:46 rivoplus volumio[11046]: info: Loading plugin "metavolumio"... Jan 14 14:05:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:46 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:46 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:05:46 rivoplus kernel: hdmitx: system: plugout Jan 14 14:05:46 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:05:47 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:47 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:47 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:47 rivoplus volumio[11046]: info: Loading plugin "airplay_emulation"... Jan 14 14:05:47 rivoplus volumio[11046]: info: Starting Shairport Sync Jan 14 14:05:47 rivoplus volumio[11046]: info: Loading plugin "cd_controller"... Jan 14 14:05:47 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:05:48 rivoplus volumio[11046]: info: Loading plugin "last_100"... Jan 14 14:05:48 rivoplus volumio[11046]: info: Loading plugin "qobuzconnect"... Jan 14 14:05:48 rivoplus volumio[11046]: info: Loading plugin "raat"... Jan 14 14:05:48 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:48 rivoplus kernel: hdmitx: system: plugin Jan 14 14:05:48 rivoplus volumio[11046]: info: RAAT Plugin loaded Jan 14 14:05:48 rivoplus volumio[11046]: info: Adding restartRAATSocket REST API Endpoint Jan 14 14:05:48 rivoplus volumio[11046]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jan 14 14:05:48 rivoplus volumio[11046]: info: Loading plugin "streaming_services"... Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:05:48 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:05:48 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:05:48 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:48 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:05:48 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:05:48 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:05:48 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:05:48 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 8 Jan 14 14:05:48 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:05:48 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:05:48 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:05:48 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:05:48 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:05:48 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:05:48 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:05:48 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:05:48 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:05:48 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:48 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:48 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:05:48 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:05:48 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:05:48 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:05:48 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d13b000 drv_status 0x0 e_type 69 e_states 0 Jan 14 14:05:48 rivoplus sudo[11145]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:49 rivoplus volumio[11046]: info: Starting Streaming Service Transparent Proxy Jan 14 14:05:49 rivoplus volumio[11046]: info: Loading plugin "tidalconnect"... Jan 14 14:05:49 rivoplus volumio[11046]: info: Loading plugin "webradio"... Jan 14 14:05:50 rivoplus volumio[11046]: info: Loading plugin "i2s_dacs"... Jan 14 14:05:50 rivoplus volumio[11046]: info: I2S DAC not set, start Auto-detection Jan 14 14:05:50 rivoplus volumio[11046]: info: Loading plugin "volumiodiscovery"... Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** For more information see Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 14:05:50 rivoplus volumio[11046]: *** WARNING *** For more information see Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** For more information see Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 14 14:05:50 rivoplus node[11046]: *** WARNING *** For more information see Jan 14 14:05:50 rivoplus volumio[11046]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 14 14:05:50 rivoplus volumio[11046]: info: Discovery: Started advertising with name: Rivoplus Jan 14 14:05:50 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 14:05:50 rivoplus volumio[11046]: info: Loading plugin "now_playing"... Jan 14 14:05:51 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:51.039+10:30 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:53098->127.0.0.1:3000: i/o timeout" Jan 14 14:05:51 rivoplus volumio[11046]: STREAMING PROXY: Starting server on port 3245 Jan 14 14:05:51 rivoplus volumio[11046]: Node JS runtime: 14 Jan 14 14:05:52 rivoplus volumio[11046]: info: Loading plugin "multiroom"... Jan 14 14:05:54 rivoplus volumio[11046]: info: Applying required configuration parameters for plugin multiroom Jan 14 14:05:54 rivoplus sudo[11175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 14 14:05:54 rivoplus sudo[11175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:54 rivoplus sudo[11175]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:54 rivoplus volumio[11046]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jan 14 14:05:54 rivoplus volumio[11046]: info: MRS: MultiRoom plugin initialized Jan 14 14:05:54 rivoplus volumio[11046]: info: MRS: STOPPING SNAPCLIENT Jan 14 14:05:54 rivoplus volumio[11046]: info: MRS: Snap server stop Jan 14 14:05:54 rivoplus sudo[11191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 14 14:05:54 rivoplus sudo[11191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:54 rivoplus volumio[11046]: info: MRS: STOPPING volumioStreaming Jan 14 14:05:54 rivoplus sudo[11193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 14 14:05:54 rivoplus sudo[11193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:54 rivoplus sudo[11191]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:54 rivoplus volumio[11046]: info: Loading plugin "outputs"... Jan 14 14:05:54 rivoplus sudo[11198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 14 14:05:54 rivoplus volumio[11046]: info: Loading plugin "albumart"... Jan 14 14:05:54 rivoplus sudo[11198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:54 rivoplus sudo[11193]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:54 rivoplus sudo[11199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 14 14:05:54 rivoplus sudo[11199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:05:54 rivoplus volumio[11046]: info: Plugin example_plugin is not enabled Jan 14 14:05:54 rivoplus volumio[11046]: info: Loading plugin "hi_res_audio"... Jan 14 14:05:54 rivoplus sudo[11198]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:54 rivoplus sudo[11199]: pam_unix(sudo:session): session closed for user root Jan 14 14:05:55 rivoplus volumio[11046]: Forking 3 albumart workers Jan 14 14:05:56 rivoplus volumio-remote-updater[2831]: [2026-01-14 14:05:56] [connect] Successful connection Jan 14 14:05:57 rivoplus volumio[11046]: Starting albumart workers Jan 14 14:05:57 rivoplus volumio[11046]: Starting albumart workers Jan 14 14:05:57 rivoplus volumio[11046]: info: Applying required configuration parameters for plugin hi_res_audio Jan 14 14:05:57 rivoplus volumio[11046]: info: Loading plugin "inputs"... Jan 14 14:05:57 rivoplus volumio[11046]: Starting albumart workers Jan 14 14:05:58 rivoplus volumio[11046]: info: Loading plugin "qobuz"... Jan 14 14:05:59 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:05:59.295+10:30 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Jan 14 14:06:00 rivoplus volumio[11046]: info: Loading plugin "smart_inputs"... Jan 14 14:06:00 rivoplus volumio[11046]: info: Loading plugin "tidal"... Jan 14 14:06:02 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:06:02.044+10:30 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:53108->127.0.0.1:3000: i/o timeout" Jan 14 14:06:02 rivoplus volumio[11046]: info: Loading plugin "rivopluscontrol"... Jan 14 14:06:02 rivoplus volumio[11046]: info: Adding this device properties Jan 14 14:06:02 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jan 14 14:06:02 rivoplus volumio[11046]: info: Setting Additional Device Volumio Properties: [object Object] Jan 14 14:06:02 rivoplus volumio[11046]: info: Loading plugin "updater_comm"... Jan 14 14:06:02 rivoplus volumio[11046]: info: Loading plugin "cec_controller"... Jan 14 14:06:03 rivoplus volumio[11046]: info: Applying required configuration parameters for plugin cec_controller Jan 14 14:06:03 rivoplus volumio[11046]: info: Plugin mpdemulation is not enabled Jan 14 14:06:03 rivoplus volumio[11046]: info: Loading plugin "rest_api"... Jan 14 14:06:03 rivoplus volumio[11046]: info: Loading plugin "websocket"... Jan 14 14:06:03 rivoplus volumio[11046]: info: Starting Socket.io Server version 2.3.0 Jan 14 14:06:03 rivoplus volumio[11046]: info: Loading plugin "fusiondsp"... Jan 14 14:06:03 rivoplus volumio[11046]: info: Applying required configuration parameters for plugin fusiondsp Jan 14 14:06:03 rivoplus volumio[11046]: info: Loading plugin "Systeminfo"... Jan 14 14:06:03 rivoplus volumio[11046]: info: Loading i18n strings for locale en Jan 14 14:06:03 rivoplus volumio[11046]: Updating browse sources language Jan 14 14:06:03 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::initPlayerControls Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:06:04 rivoplus volumio[11046]: Express server listening on port 3000 Jan 14 14:06:04 rivoplus volumio[11046]: [Metrics] WebUI: 23s 333.57ms Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreStateMachine::resetVolumioState Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreStateMachine::getcurrentVolume Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::volumioRetrievevolume Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreStateMachine::pushState Jan 14 14:06:04 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::volumioPushState Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:04 rivoplus sudo[11281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 14 14:06:04 rivoplus sudo[11281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:04 rivoplus sudo[11281]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:04 rivoplus volumio[11046]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 14 14:06:04 rivoplus volumio[11046]: info: Completed loading Core Plugins Jan 14 14:06:04 rivoplus sudo[11285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 14 14:06:04 rivoplus volumio[11046]: info: Preparing to generate the ALSA configuration file Jan 14 14:06:04 rivoplus sudo[11285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:04 rivoplus sudo[11285]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:04 rivoplus volumio[11046]: info: Volumio Network Manager: Network status updated: 2 Jan 14 14:06:04 rivoplus volumio[11046]: info: MRS: Removed streaming files Jan 14 14:06:04 rivoplus volumio[11046]: info: MRS: volumioStreaming STOPPED Jan 14 14:06:04 rivoplus volumio[11046]: info: MRS: SNAPSERVER STOPPED Jan 14 14:06:04 rivoplus volumio[11046]: info: MRS: SNAPCLIENT STOPPED Jan 14 14:06:04 rivoplus volumio[11046]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 14 14:06:04 rivoplus volumio[11046]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:06:04 rivoplus volumio[11046]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:06:04 rivoplus volumio[11046]: info: Reading ALSA contributions from plugins. Jan 14 14:06:04 rivoplus volumio[11046]: 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: 1 Jan 14 14:06:04 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 2 Jan 14 14:06:04 rivoplus volumio[11046]: 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: 3 Jan 14 14:06:04 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Jan 14 14:06:04 rivoplus volumio[11046]: 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: 5 Jan 14 14:06:04 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Jan 14 14:06:04 rivoplus volumio[11046]: 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: 7 Jan 14 14:06:04 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Jan 14 14:06:04 rivoplus volumio[11046]: 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 Jan 14 14:06:04 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:06:04 rivoplus volumio[11046]: info: Discovery: Getting this device information Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:04 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:06:04 rivoplus volumio[11046]: info: Discovery: Getting this device information Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:04 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:06:04 rivoplus volumio[11046]: info: Discovery: Getting this device information Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:04 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:04 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:05 rivoplus volumio[11046]: info: Reloading queue from file Jan 14 14:06:05 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143 from 192.168.50.236 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Jan 14 14:06:05 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreStateMachine::setRepeat null single undefined Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreStateMachine::pushState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioPushState Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreStateMachine::setRandom null Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreStateMachine::pushState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioPushState Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 14 14:06:05 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143:3000 from 192.168.50.236 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jan 14 14:06:05 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetVisibleSources Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 14 14:06:05 rivoplus sudo[11312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 14 14:06:05 rivoplus sudo[11312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 14 14:06:05 rivoplus volumio[11046]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 14 14:06:05 rivoplus volumio[11046]: info: Received Get System Info Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:06:05 rivoplus volumio[11046]: info: Discovery: Getting this device information Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: Listing playlists Jan 14 14:06:05 rivoplus sudo[11312]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 14 14:06:05 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Jan 14 14:06:05 rivoplus volumio[11046]: info: Upmpdcli Daemon Started Jan 14 14:06:05 rivoplus volumio[11046]: info: Discovery: adding 72f71f5e-f6ec-446b-b928-42920f530119 Jan 14 14:06:05 rivoplus volumio[11046]: info: Discovery: Found device Rivoplus Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:05 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output for this device Jan 14 14:06:05 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding audio output: Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding audio output: Jan 14 14:06:05 rivoplus volumio[11046]: info: Asound.conf file unchanged, so no further update is needed Jan 14 14:06:05 rivoplus volumio[11046]: info: Output device has changed, restarting MPD Jan 14 14:06:05 rivoplus sudo[11315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:06:05 rivoplus sudo[11315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus volumio[11046]: info: Output device has changed, restarting Shairport Sync Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:05 rivoplus sudo[11315]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus sudo[11318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:06:05 rivoplus sudo[11318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 14 14:06:05 rivoplus volumio[11046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:06:05 rivoplus volumio[11046]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:05 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:06:05 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:06:05 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:06:05 rivoplus volumio[11046]: info: ___________ START PLUGINS ___________ Jan 14 14:06:05 rivoplus volumio[11046]: info: ControllerMpd::onStart: Initializing MPD Jan 14 14:06:05 rivoplus volumio[11046]: info: Creating MPD Configuration file Jan 14 14:06:05 rivoplus sudo[11334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jan 14 14:06:05 rivoplus sudo[11334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus sudo[11334]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus sudo[11337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:06:05 rivoplus sudo[11337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:06:05 rivoplus sudo[11337]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 14:06:05 rivoplus volumio[11046]: info: [1768361765738] CoreMusicLibrary::Adding element Media Servers Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:06:05 rivoplus sudo[11325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:06:05 rivoplus sudo[11325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus volumio[11046]: info: UPNP Browser: Client initialized successfully Jan 14 14:06:05 rivoplus sudo[11325]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus sudo[11340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:06:05 rivoplus sudo[11340]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 14 14:06:05 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 14 14:06:05 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:06:05 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding METAVOLUMIO REST API Endpoints Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 14 14:06:05 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:05 rivoplus sudo[11345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivoplus Jan 14 14:06:05 rivoplus sudo[11345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:05 rivoplus sudo[11345]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:05 rivoplus volumio[11046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:05 rivoplus volumio[11046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:06:05 rivoplus volumio[11046]: info: Preparing CD Folders Jan 14 14:06:05 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding CD REST API Endpoints Jan 14 14:06:05 rivoplus volumio[11046]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 14 14:06:05 rivoplus volumio[11046]: info: Starting UDEV Watcher for CD Jan 14 14:06:05 rivoplus volumio[11046]: info: Detecting CD presence with UDEV Jan 14 14:06:05 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 14 14:06:06 rivoplus sudo[11346]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:06:06 rivoplus sudo[11346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus kernel: hdmitx: system: plugout Jan 14 14:06:06 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:06:06 rivoplus sudo[11346]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 14:06:06 rivoplus volumio[11046]: info: [1768361766044] CoreMusicLibrary::Adding element Last_100 Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 14 14:06:06 rivoplus volumio[11046]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 14 14:06:06 rivoplus volumio[11046]: info: Starting RAAT Plugin Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 14 14:06:06 rivoplus volumio[11046]: info: Additional UI Settings Added for plugin music_service/raat Jan 14 14:06:06 rivoplus volumio[11046]: info: Registering DSP Elements listener and retrieving current ones Jan 14 14:06:06 rivoplus volumio[11046]: info: Additional DSP elements updated Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:06 rivoplus volumio[11046]: info: Updating RAAT Signal Path Jan 14 14:06:06 rivoplus volumio[11046]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 14 14:06:06 rivoplus volumio[11046]: info: Streaming services startup Jan 14 14:06:06 rivoplus volumio[11046]: info: Starting Streaming Daemon Jan 14 14:06:06 rivoplus sudo[11362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:06:06 rivoplus sudo[11362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus sudo[11362]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:06 rivoplus sudo[11365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 14 14:06:06 rivoplus sudo[11365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus sudo[11366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:06:06 rivoplus sudo[11366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus sudo[11365]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:06 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:06:06 rivoplus qobuz-connect[10486]: 20260114 14:06:06.278 [10486.10486] INFO SampleApp: Stopping Local configuration server Jan 14 14:06:06 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:06:06 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:06:06 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:06:06 rivoplus sudo[11366]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 14 14:06:06 rivoplus volumio[11046]: info: [1768361766344] CoreMusicLibrary::Adding element Webradio Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:06:06 rivoplus volumio[11046]: info: Initializing BBC Radios Jan 14 14:06:06 rivoplus sudo[11376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:06:06 rivoplus sudo[11376]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 14 14:06:06 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 14 14:06:06 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 14 14:06:06 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 14 14:06:06 rivoplus sudo[11376]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:06 rivoplus volumio[11046]: info: [now-playing] ConfigUpdater: config is up to date. Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 14 14:06:06 rivoplus volumio[11046]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:06:06 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:06 rivoplus volumio[11046]: error: Hi Res Audio Failed Login: Missing Login Data Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 14 14:06:06 rivoplus volumio[11046]: info: Initializing Serial Communication on port /dev/ttyS3 Jan 14 14:06:06 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Jan 14 14:06:06 rivoplus volumio[11046]: info: Touch Event Listener Process Starting Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding inputs REST Endpoints Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 14 14:06:06 rivoplus volumio[11046]: info: Scanning Audio Inputs Jan 14 14:06:06 rivoplus sudo[11397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jan 14 14:06:06 rivoplus sudo[11397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus volumio[11046]: info: Checking against Known Cards name Jan 14 14:06:06 rivoplus volumio[11046]: info: Checking against Known Cards name Jan 14 14:06:06 rivoplus volumio[11046]: info: Checking against Known Cards name Jan 14 14:06:06 rivoplus volumio[11046]: info: Checking against Known Cards name Jan 14 14:06:06 rivoplus volumio[11046]: info: Checking against Known Cards name Jan 14 14:06:06 rivoplus volumio[11046]: info: Adding Server instance for streaming Jan 14 14:06:06 rivoplus volumio[11046]: info: Refreshing TIDAL token Jan 14 14:06:06 rivoplus sudo[11404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jan 14 14:06:06 rivoplus sudo[11404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:06 rivoplus sudo[11404]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus sudo[11407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jan 14 14:06:07 rivoplus sudo[11407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:07 rivoplus sudo[11407]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus sudo[11410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jan 14 14:06:07 rivoplus sudo[11410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:07 rivoplus sudo[11410]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:06:07 rivoplus sudo[11413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jan 14 14:06:07 rivoplus sudo[11413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:07 rivoplus sudo[11413]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus volumio[11046]: info: Apply VIM3L Onboard LEDs Settings Jan 14 14:06:07 rivoplus volumio[11046]: info: Turning On Onboard LEDs Jan 14 14:06:07 rivoplus sudo[11417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jan 14 14:06:07 rivoplus sudo[11417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:07 rivoplus volumio[11046]: info: Starting CEC Event listener Jan 14 14:06:07 rivoplus sudo[11417]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus volumio[11046]: info: Loading i18n strings for locale en Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:07 rivoplus volumio[11046]: info: FusionDsp - mixtype--------------------- None Jan 14 14:06:07 rivoplus volumio[11046]: info: Volumio Calling Home Jan 14 14:06:07 rivoplus sudo[11421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jan 14 14:06:07 rivoplus sudo[11421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:07 rivoplus sudo[11421]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:07 rivoplus volumio[11046]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 14 14:06:07 rivoplus volumio[11046]: (node:11046) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 14 14:06:07 rivoplus volumio[11046]: (Use `node --trace-deprecation ...` to show where the warning was created) Jan 14 14:06:07 rivoplus volumio[11046]: info: Preparing to generate the ALSA configuration file Jan 14 14:06:07 rivoplus volumio[11046]: info: Stopping AccessToken refresher cron for QOBUZ Jan 14 14:06:07 rivoplus volumio[11046]: info: AccessToken refresher cron started for QOBUZ Jan 14 14:06:07 rivoplus volumio[11046]: info: Adding QOBUZ REST API Endpoints Jan 14 14:06:07 rivoplus volumio[11046]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 14 14:06:07 rivoplus kernel: hdmitx: system: plugin Jan 14 14:06:07 rivoplus volumio[11046]: info: [now-playing] App is listening on port 4004. Jan 14 14:06:07 rivoplus volumio[11046]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 14:06:07 rivoplus volumio[11046]: info: Discovery: Getting this device information Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:07 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:07 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:06:07 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:06:07 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:06:07 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:06:07 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:06:07 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:06:07 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:06:07 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:06:07 rivoplus volumio[11046]: info: Serial port opened successfully Jan 14 14:06:07 rivoplus volumio[11046]: info: Sending serial start messages Jan 14 14:06:07 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:06:07 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:06:07 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:06:07 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:06:07 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:06:07 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:06:07 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:06:07 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:06:07 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:07 rivoplus volumio[11046]: info: Reporting MCU Network Status: 2 Jan 14 14:06:07 rivoplus volumio[11046]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:06:07 rivoplus volumio[11046]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:06:07 rivoplus volumio[11046]: info: Reading ALSA contributions from plugins. Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:06:07 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:06:07 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:06:07 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:06:07 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:06:07 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:06:07 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:06:07 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:06:07 rivoplus volumio[11046]: 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: 11 Jan 14 14:06:07 rivoplus volumio[11046]: error: Cannot start Volumio Streaming Daemon Jan 14 14:06:07 rivoplus volumio[11046]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 14 14:06:07 rivoplus volumio[11046]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 14 14:06:07 rivoplus volumio[11046]: info: Bluetooth name changed to Rivoplus Jan 14 14:06:08 rivoplus volumio[11046]: info: MPD Permissions set Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting Device Volume Override Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 14 14:06:08 rivoplus volumio[11046]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 14 14:06:08 rivoplus volumio[11046]: info: Disabling external Volume Control Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreStateMachine::pushState Jan 14 14:06:08 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioPushState Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:08 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:08 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:06:08 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreStateMachine::pushState Jan 14 14:06:08 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioPushState Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:08 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:08 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:06:08 rivoplus volumio[11046]: info: MRS: Pushing multiroomSync output Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:06:08 rivoplus volumio[11046]: info: Executing endpoint qc_getconfig Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.305 [11375.11375] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:06:08 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.316 [11375.11375] INFO VolumeManager: [0xacebb7d8]: Setting new playback volume: 75 Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.317 [11375.11375] INFO VolumeManager: [0xacebb7d8]: Setting new mute state: 0 Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.317 [11375.11375] INFO QobuzConnect: [0xacebc1a8]: Client initialized! Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.317 [11375.11375] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:06:08 rivoplus volumio[11046]: verbose: New Socket.io Connection to 192.168.50.143:3000 from 192.168.50.236 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.344 [11375.11375] INFO LocalConfigManager: [0xacebb0b8]: Starting Local Configuration server Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.344 [11375.11375] INFO SampleApp: Starting Local configuration server Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.345 [11375.11375] INFO SampleApp: Connected to UNIX socket client 0xaceb0ed8 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting Additional System Software info: Hardware Revision: 1.0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting HW Firmware info: undefined Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting HW Version info: 1.0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting HW Firmware info: 0.3.0 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 14 14:06:08 rivoplus volumio[11046]: info: Setting HW Version info: 1.0 Jan 14 14:06:08 rivoplus volumio[11046]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 14 14:06:08 rivoplus volumio[11046]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:06:08 rivoplus volumio[11046]: info: Reporting Headphone State: false Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: Updating RAAT Signal Path Jan 14 14:06:08 rivoplus volumio[11046]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 14 14:06:08 rivoplus volumio[11046]: info: MCU Signalled Sleep Mode Disabled Jan 14 14:06:08 rivoplus volumio[11046]: info: Enabling Advanced system settings configuration Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jan 14 14:06:08 rivoplus volumio[11046]: info: Additional UI Settings Added for plugin music_service/inputs Jan 14 14:06:08 rivoplus volumio[11046]: info: MCU Signalled Auto Boot Mode On Power Active Jan 14 14:06:08 rivoplus sudo[11437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jan 14 14:06:08 rivoplus sudo[11437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:08 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Jan 14 14:06:08 rivoplus sudo[11437]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:08 rivoplus qobuz-connect[11375]: 20260114 14:06:08.455 [11375.11375] INFO SampleApp: Playback volume changed: 75 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 14 14:06:08 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 14 14:06:08 rivoplus volumio[11046]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:06:08 rivoplus volumio[11046]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:06:08 rivoplus volumio[11046]: info: Volumio called home Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus mpd[11357]: Jan 14 14:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 14:06:08 rivoplus systemd[1]: Started Music Player Daemon. Jan 14 14:06:08 rivoplus sudo[11340]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:08 rivoplus sudo[11318]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:08 rivoplus volumio[11046]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jan 14 14:06:08 rivoplus volumio[11046]: info: MP1 GPIO: Signalled system ready via GPIO Jan 14 14:06:08 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jan 14 14:06:08 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 15 Jan 14 14:06:08 rivoplus volumio[11046]: info: CoreCommandRouter::volumioGetState Jan 14 14:06:08 rivoplus volumio[11046]: info: CorePlayQueue::getTrack 0 Jan 14 14:06:08 rivoplus sudo[11455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jan 14 14:06:08 rivoplus sudo[11455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:08 rivoplus sudo[11455]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:08 rivoplus sudo[11458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 14 14:06:08 rivoplus sudo[11458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:08 rivoplus sudo[11458]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:10 rivoplus volumio[11046]: info: Access Token successfully retrieved Jan 14 14:06:10 rivoplus volumio[11046]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 14 Jan 14 14:06:10 rivoplus volumio[11046]: info: Volumio BT Module successfully started Jan 14 14:06:10 rivoplus volumio[11046]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 14 14:06:10 rivoplus volumio[11046]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 14 14:06:10 rivoplus volumio[11046]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 14 14:06:10 rivoplus volumio[11046]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 14 14:06:10 rivoplus volumio[11046]: info: camilladsp service started and running in background, instance 1 Jan 14 14:06:10 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:10 rivoplus volumio[11046]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 14 14:06:10 rivoplus volumio[11046]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 14 14:06:10 rivoplus volumio[11046]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 14 14:06:10 rivoplus volumio[11046]: info: FusionDsp loaded Jan 14 14:06:10 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:06:10 rivoplus sudo[11473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 14 14:06:10 rivoplus sudo[11473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:06:10 rivoplus volumio[11046]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 14 14:06:10 rivoplus volumio[11046]: info: Adding Signal Path Element [object Object] Jan 14 14:06:10 rivoplus volumio[11046]: info: Adding fusiondspeq DSP Signal Path Element Jan 14 14:06:10 rivoplus volumio[11046]: info: Additional DSP elements updated Jan 14 14:06:10 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:10 rivoplus volumio[11046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:06:10 rivoplus volumio[11046]: info: Updating RAAT Signal Path Jan 14 14:06:10 rivoplus volumio[11046]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 14 14:06:10 rivoplus volumio[11046]: info: FusionDsp - ---- installed callbackRead Jan 14 14:06:10 rivoplus volumio[11046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:06:10 rivoplus systemd[1]: Started FusionDsp Daemon. Jan 14 14:06:10 rivoplus sudo[11473]: pam_unix(sudo:session): session closed for user root Jan 14 14:06:10 rivoplus volumio[11046]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 14 14:06:10 rivoplus volumio[11046]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jan 14 14:06:10 rivoplus volumio[11046]: at onErrorNT (internal/child_process.js:472:16) Jan 14 14:06:10 rivoplus volumio[11046]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 14 14:06:10 rivoplus volumio[11046]: at runNextTicks (internal/process/task_queues.js:64:3) Jan 14 14:06:10 rivoplus volumio[11046]: at listOnTimeout (internal/timers.js:526:9) Jan 14 14:06:10 rivoplus volumio[11046]: at processTimers (internal/timers.js:500:7) { Jan 14 14:06:10 rivoplus volumio[11046]: errno: -2, Jan 14 14:06:10 rivoplus volumio[11046]: code: 'ENOENT', Jan 14 14:06:10 rivoplus volumio[11046]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 14 14:06:10 rivoplus volumio[11046]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 14 14:06:10 rivoplus volumio[11046]: spawnargs: [ Jan 14 14:06:10 rivoplus volumio[11046]: '-p', Jan 14 14:06:10 rivoplus volumio[11046]: 9876, Jan 14 14:06:10 rivoplus volumio[11046]: '-o', Jan 14 14:06:10 rivoplus volumio[11046]: '/tmp/camilladsp.log', Jan 14 14:06:10 rivoplus volumio[11046]: '-l', Jan 14 14:06:10 rivoplus volumio[11046]: 'warn', Jan 14 14:06:10 rivoplus volumio[11046]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 14 14:06:10 rivoplus volumio[11046]: ] Jan 14 14:06:10 rivoplus volumio[11046]: } Jan 14 14:06:10 rivoplus volumio[11046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:06:11 rivoplus sudo[11485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-14 14:05 Jan 14 14:06:11 rivoplus sudo[11485]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"