-- Logs begin at Tue 2025-03-11 19:58:41 CET, end at Wed 2025-03-12 17:40:16 CET. -- Mar 12 17:39:00 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 17:39:00 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:00 rivoplus volumio[16716]: error: Hi Res Audio Failed Login: Missing Login Data Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 12 17:39:00 rivoplus volumio[16716]: info: Initializing Serial Communication on port /dev/ttyS3 Mar 12 17:39:00 rivoplus volumio[16716]: info: Touch Event Listener Process Starting Mar 12 17:39:00 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding inputs REST Endpoints Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 12 17:39:00 rivoplus volumio[16716]: info: Scanning Audio Inputs Mar 12 17:39:00 rivoplus sudo[16955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Mar 12 17:39:00 rivoplus sudo[16955]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus volumio[16716]: info: Checking against Known Cards name Mar 12 17:39:00 rivoplus volumio[16716]: info: Checking against Known Cards name Mar 12 17:39:00 rivoplus volumio[16716]: info: Checking against Known Cards name Mar 12 17:39:00 rivoplus volumio[16716]: info: Checking against Known Cards name Mar 12 17:39:00 rivoplus volumio[16716]: info: Checking against Known Cards name Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding Server instance for streaming Mar 12 17:39:00 rivoplus volumio[16716]: info: Refreshing TIDAL token Mar 12 17:39:00 rivoplus sudo[16965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Mar 12 17:39:00 rivoplus sudo[16965]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus sudo[16965]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus sudo[16968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Mar 12 17:39:00 rivoplus sudo[16968]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus sudo[16968]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus sudo[16971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Mar 12 17:39:00 rivoplus sudo[16971]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus sudo[16971]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus sudo[16974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Mar 12 17:39:00 rivoplus sudo[16974]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus sudo[16974]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus volumio[16716]: info: Apply VIM3L Onboard LEDs Settings Mar 12 17:39:00 rivoplus volumio[16716]: info: Turning On Onboard LEDs Mar 12 17:39:00 rivoplus sudo[16978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 12 17:39:00 rivoplus sudo[16978]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus volumio[16716]: info: Starting CEC Event listener Mar 12 17:39:00 rivoplus sudo[16978]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus volumio[16716]: info: Volumio Calling Home Mar 12 17:39:00 rivoplus sudo[16982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 12 17:39:00 rivoplus sudo[16982]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:00 rivoplus sudo[16982]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:00 rivoplus volumio[16716]: (node:16716) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Mar 12 17:39:00 rivoplus volumio[16716]: (Use `node --trace-deprecation ...` to show where the warning was created) Mar 12 17:39:00 rivoplus volumio[16716]: info: Stopping AccessToken refresher cron for QOBUZ Mar 12 17:39:00 rivoplus volumio[16716]: info: AccessToken refresher cron started for QOBUZ Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding QOBUZ REST API Endpoints Mar 12 17:39:00 rivoplus volumio[16716]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 12 17:39:01 rivoplus volumio[16716]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 12 17:39:01 rivoplus volumio[16716]: info: Serial port opened successfully Mar 12 17:39:01 rivoplus volumio[16716]: info: Sending serial start messages Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: Reporting MCU Network Status: 2 Mar 12 17:39:01 rivoplus volumio[16716]: verbose: New Socket.io Connection to 192.168.1.124 from 192.168.1.127 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 12 17:39:01 rivoplus volumio[16716]: error: Cannot start Volumio Streaming Daemon Mar 12 17:39:01 rivoplus volumio[16716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 12 17:39:01 rivoplus volumio[16716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 12 17:39:01 rivoplus volumio[16716]: info: MPD Permissions set Mar 12 17:39:01 rivoplus volumio[16716]: info: MPD Permissions set Mar 12 17:39:01 rivoplus volumio[16716]: info: Upmpdcli Daemon Started Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting Device Volume Override Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 12 17:39:01 rivoplus volumio[16716]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 12 17:39:01 rivoplus volumio[16716]: info: Disabling external Volume Control Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:01 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:01 rivoplus volumio-remote-updater[2859]: [2025-03-12 17:39:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741797539 101 Mar 12 17:39:01 rivoplus volumio[16716]: 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: 12 Mar 12 17:39:01 rivoplus volumio[16716]: info: Volumio called home Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting Additional System Software info: Hardware Revision: 1.0 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting HW Firmware info: undefined Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting HW Version info: 1.0 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.7 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting HW Firmware info: 0.2.7 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 17:39:01 rivoplus volumio[16716]: info: Setting HW Version info: 1.0 Mar 12 17:39:01 rivoplus volumio[16716]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 12 17:39:01 rivoplus volumio[16716]: info: MCU Signalled Headphone Mode Disabled Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 12 17:39:01 rivoplus volumio[16716]: info: Reporting Headphone State: false Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: Updating RAAT Signal Path Mar 12 17:39:01 rivoplus volumio[16716]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Mar 12 17:39:01 rivoplus volumio[16716]: info: MCU Signalled Sleep Mode Disabled Mar 12 17:39:01 rivoplus volumio[16716]: info: Enabling Advanced system settings configuration Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Mar 12 17:39:01 rivoplus volumio[16716]: info: Additional UI Settings Added for plugin music_service/inputs Mar 12 17:39:01 rivoplus volumio[16716]: info: MCU Signalled Auto Boot Mode On Power Disabled Mar 12 17:39:01 rivoplus sudo[17002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Mar 12 17:39:01 rivoplus sudo[17002]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:01 rivoplus sudo[17002]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:01 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Mar 12 17:39:01 rivoplus volumio[16716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 12 17:39:01 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:01 rivoplus volumio[16716]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Mar 12 17:39:01 rivoplus volumio[16716]: info: Access Token successfully retrieved Mar 12 17:39:01 rivoplus mpd[16932]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 12 17:39:01 rivoplus mpd[16932]: output: No 'audio_output' defined in config file Mar 12 17:39:02 rivoplus kernel: aml_tdm_open Mar 12 17:39:02 rivoplus kernel: Not init audio effects Mar 12 17:39:02 rivoplus kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Mar 12 17:39:02 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 12 17:39:02 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 12 17:39:02 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Mar 12 17:39:02 rivoplus kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d270c18, id(1), clksel(1) Mar 12 17:39:02 rivoplus kernel: aml_dai_set_tdm_fmt(), fmt not change Mar 12 17:39:02 rivoplus kernel: dump_pcm_setting(ffffffc03d270c18) Mar 12 17:39:02 rivoplus kernel: pcm_mode(1) Mar 12 17:39:02 rivoplus kernel: sysclk(12288000) Mar 12 17:39:02 rivoplus kernel: sysclk_bclk_ratio(4) Mar 12 17:39:02 rivoplus kernel: bclk(3072000) Mar 12 17:39:02 rivoplus kernel: bclk_lrclk_ratio(64) Mar 12 17:39:02 rivoplus kernel: lrclk(48000) Mar 12 17:39:02 rivoplus kernel: tx_mask(0x3) Mar 12 17:39:02 rivoplus kernel: rx_mask(0x3) Mar 12 17:39:02 rivoplus kernel: slots(2) Mar 12 17:39:02 rivoplus kernel: slot_width(32) Mar 12 17:39:02 rivoplus kernel: lane_mask_in(0x2) Mar 12 17:39:02 rivoplus kernel: lane_mask_out(0x1) Mar 12 17:39:02 rivoplus kernel: lane_oe_mask_in(0x0) Mar 12 17:39:02 rivoplus kernel: lane_oe_mask_out(0x0) Mar 12 17:39:02 rivoplus kernel: lane_lb_mask_in(0x0) Mar 12 17:39:02 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 12 17:39:02 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 12 17:39:02 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Mar 12 17:39:02 rivoplus kernel: aml_dai_set_clkdiv, div 4, clksel(1) Mar 12 17:39:02 rivoplus kernel: aml_dai_set_bclk_ratio, select I2S mode Mar 12 17:39:02 rivoplus kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Mar 12 17:39:02 rivoplus kernel: aml_tdm_prepare(), reset fddr Mar 12 17:39:02 rivoplus kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Mar 12 17:39:02 rivoplus kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Mar 12 17:39:02 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 12 17:39:02 rivoplus kernel: tdm playback mute: 0, lane_cnt = 8 Mar 12 17:39:02 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 12 17:39:02 rivoplus kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Mar 12 17:39:02 rivoplus kernel: tdm playback mute: 1, lane_cnt = 8 Mar 12 17:39:02 rivoplus kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Mar 12 17:39:02 rivoplus mpd[16932]: output: Successfully detected a sndio audio device Mar 12 17:39:02 rivoplus mpd[16932]: zeroconf: No global port, disabling zeroconf Mar 12 17:39:02 rivoplus systemd[1]: Started Music Player Daemon. Mar 12 17:39:02 rivoplus sudo[16903]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus sudo[16919]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus volumio[16716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:02 rivoplus sudo[17020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Mar 12 17:39:02 rivoplus sudo[17020]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:02 rivoplus volumio[16716]: info: MP1 GPIO: Signalled system ready via GPIO Mar 12 17:39:02 rivoplus sudo[17020]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus volumio[16716]: verbose: New Socket.io Connection to 192.168.1.124 from 192.168.1.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: Starting Shairport Sync Mar 12 17:39:02 rivoplus volumio[16716]: info: Starting Shairport Sync Mar 12 17:39:02 rivoplus sudo[17025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 17:39:02 rivoplus sudo[17025]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:02 rivoplus volumio[16716]: info: Starting Shairport Sync Mar 12 17:39:02 rivoplus sudo[17028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 17:39:02 rivoplus sudo[17028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:02 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 17:39:02 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 17:39:02 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus volumio[16716]: info: Adding Inputs via Serial API Mar 12 17:39:02 rivoplus volumio[16716]: info: Adding Advanced Audio Settings via Serial API Mar 12 17:39:02 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 12 17:39:02 rivoplus volumio[16716]: info: Additional UI Settings Added for plugin music_service/inputs Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 17:39:02 rivoplus sudo[17025]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 17:39:02 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 17:39:02 rivoplus sudo[17031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 17:39:02 rivoplus sudo[17031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:02 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 12 17:39:02 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 17:39:02 rivoplus volumio[16716]: info: Listing playlists Mar 12 17:39:02 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus sudo[17028]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 17:39:02 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 12 17:39:02 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 17:39:02 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 17:39:02 rivoplus sudo[17031]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:02 rivoplus volumio[16716]: error: MPD error: The expression evaluated to a falsy value: Mar 12 17:39:02 rivoplus volumio[16716]: assert.ok(self.idling) Mar 12 17:39:02 rivoplus volumio[16716]: error: The expression evaluated to a falsy value: Mar 12 17:39:02 rivoplus volumio[16716]: assert.ok(self.idling) Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: MPD running with PID16932 Mar 12 17:39:02 rivoplus volumio[16716]: ,establishing connection Mar 12 17:39:02 rivoplus volumio[16716]: error: updateQueue error: null Mar 12 17:39:02 rivoplus volumio[16716]: 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: 16 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: Shairport-Sync Started Mar 12 17:39:02 rivoplus volumio[16716]: Error adding Membership: Error: addMembership EINVAL Mar 12 17:39:02 rivoplus volumio[16716]: info: Shairport-Sync Started Mar 12 17:39:02 rivoplus volumio[16716]: info: Shairport-Sync Started Mar 12 17:39:02 rivoplus volumio[16716]: info: Successfully retrieved User Session From TIDAL Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::servicePushState Mar 12 17:39:02 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:02 rivoplus volumio[16716]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 17:39:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 17:39:02 rivoplus volumio[16716]: info: Listing playlists Mar 12 17:39:02 rivoplus volumio[16716]: error: updateQueue error: null Mar 12 17:39:03 rivoplus volumio[16716]: info: TidalConnect service stoped! Mar 12 17:39:03 rivoplus volumio[16716]: info: MCU Signalled Playback Inactive Mar 12 17:39:03 rivoplus volumio[16716]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 12 17:39:03 rivoplus volumio[16716]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 12 17:39:03 rivoplus sudo[17050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 12 17:39:03 rivoplus sudo[17050]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:03 rivoplus volumio[16716]: info: Successfully retrieved User Subscription From TIDAL Mar 12 17:39:03 rivoplus volumio[16716]: info: Adding TIDAL to Browse Sources Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 17:39:03 rivoplus volumio[16716]: info: [1741797543310] CoreMusicLibrary::Adding element TIDAL Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 17:39:03 rivoplus volumio[16716]: Cannot find translation for source TIDAL Mar 12 17:39:03 rivoplus volumio[16716]: info: Stopping AccessToken refresher cron Mar 12 17:39:03 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Mar 12 17:39:03 rivoplus sudo[17050]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:03 rivoplus volumio[16716]: info: AccessToken refresher cron started Mar 12 17:39:03 rivoplus volumio[16716]: info: Adding TIDAL REST API Endpoints Mar 12 17:39:03 rivoplus volumio[16716]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 12 17:39:03 rivoplus volumio[16716]: info: Turning Off Onboard LEDs Mar 12 17:39:03 rivoplus sudo[17057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 12 17:39:03 rivoplus sudo[17057]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:03 rivoplus sudo[17057]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:03 rivoplus sudo[17060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 12 17:39:03 rivoplus sudo[17060]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:03 rivoplus sudo[17060]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:03 rivoplus volumio[16716]: info: Onboard LEDs initialized Mar 12 17:39:03 rivoplus volumio[16716]: info: Executing endpoint tc_getconfig Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 12 17:39:03 rivoplus vtcs[17052]: STARTING TidalConnect services, version: 1.4.0.34 Mar 12 17:39:03 rivoplus vtcs[17052]: STARTED TidalConnect services. Mar 12 17:39:03 rivoplus volumio[16716]: info: Executing endpoint tc_connect Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 12 17:39:03 rivoplus volumio[16716]: info: Connecting to TidalConnect Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::servicePushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:03 rivoplus volumio[16716]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::servicePushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:03 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:03 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:03 rivoplus volumio[16716]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Mar 12 17:39:04 rivoplus volumio[16716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 12 17:39:04 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:04 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:05 rivoplus volumio[16716]: verbose: New Socket.io Connection to 192.168.1.124 from 192.168.1.127 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:05 rivoplus mpd[16932]: exception: No database Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:05 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 17:39:05 rivoplus volumio[16716]: info: Listing playlists Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 17:39:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 17:39:06 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetQueue Mar 12 17:39:06 rivoplus volumio[16716]: info: CoreStateMachine::getQueue Mar 12 17:39:06 rivoplus volumio[16716]: info: CorePlayQueue::getQueue Mar 12 17:39:06 rivoplus volumio[16716]: info: TidalConnect service started! Mar 12 17:39:06 rivoplus volumio[16716]: info: Completed starting Core Plugins Mar 12 17:39:06 rivoplus volumio[16716]: info: ------------------------------------------- Mar 12 17:39:06 rivoplus volumio[16716]: info: ----- MyVolumio plugins startup ---- Mar 12 17:39:06 rivoplus volumio[16716]: info: ------------------------------------------- Mar 12 17:39:06 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 12 17:39:07 rivoplus mpd[16932]: exception: No database Mar 12 17:39:07 rivoplus mpd[16932]: exception: No database Mar 12 17:39:07 rivoplus volumio[16716]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Mar 12 17:39:07 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:07 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:07 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetQueue Mar 12 17:39:07 rivoplus volumio[16716]: info: CoreStateMachine::getQueue Mar 12 17:39:07 rivoplus volumio[16716]: info: CorePlayQueue::getQueue Mar 12 17:39:09 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:09 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:09 rivoplus mpd[16932]: exception: No database Mar 12 17:39:10 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:10 rivoplus volumio[16716]: info: Checking for updated MCU Firmware Mar 12 17:39:10 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 17:39:10 rivoplus volumio[16716]: info: Firware on device is on latest version, no need to update Mar 12 17:39:12 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:13 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:13 rivoplus mpd[16932]: exception: No database Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 12 17:39:14 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 12 17:39:15 rivoplus volumio[16716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 12 17:39:15 rivoplus volumio[16716]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 12 17:39:15 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:15 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:15 rivoplus volumio[16716]: info: Starting MyVolumio Remote Streaming Endpoints Mar 12 17:39:15 rivoplus volumio[16716]: info: MyVolumio login type: Token Mar 12 17:39:16 rivoplus volumio[16716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 12 17:39:16 rivoplus volumio[16716]: 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' Mar 12 17:39:16 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:16 rivoplus volumio[16716]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 12 17:39:17 rivoplus volumio[16716]: info: MyVolumio token set successfully Mar 12 17:39:17 rivoplus volumio[16716]: info: MYVOLUMIO: Adding device Mar 12 17:39:17 rivoplus volumio[16716]: info: MYVOLUMIO: Evaluating Server Mar 12 17:39:17 rivoplus volumio[16716]: info: MyVolumio status changed Mar 12 17:39:17 rivoplus volumio[16716]: info: Streaming services startup Mar 12 17:39:17 rivoplus volumio[16716]: info: Starting Streaming Daemon Mar 12 17:39:17 rivoplus volumio[16716]: info: Removing browser output: myVolumio user plan is not superstar Mar 12 17:39:17 rivoplus volumio[16716]: info: Removing audio output: Mar 12 17:39:17 rivoplus volumio[16716]: info: Stoppping Tunnel 1 Mar 12 17:39:17 rivoplus sudo[17138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 12 17:39:17 rivoplus sudo[17138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:17 rivoplus sudo[17138]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:17 rivoplus volumio[16716]: error: Cannot start Volumio Streaming Daemon Mar 12 17:39:17 rivoplus volumio[16716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 12 17:39:17 rivoplus volumio[16716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 12 17:39:17 rivoplus sudo[17142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 12 17:39:18 rivoplus sudo[17142]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:18 rivoplus sudo[17142]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:18 rivoplus volumio[16716]: info: Remote SSH Stopped Mar 12 17:39:18 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:18 rivoplus mpd[16932]: exception: No database Mar 12 17:39:18 rivoplus volumio[16716]: info: Setting Geolocation for MyVolumio to eu6 Mar 12 17:39:18 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:18 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:18 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:18 rivoplus volumio[16716]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 12 17:39:19 rivoplus volumio[16716]: info: Updating MyVolumio device info Mar 12 17:39:19 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:19 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:19 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:20 rivoplus volumio[16716]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 12 17:39:22 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:22 rivoplus mpd[16932]: exception: No database Mar 12 17:39:22 rivoplus volumio[16716]: info: MYVOLUMIO: Adding device Mar 12 17:39:22 rivoplus volumio[16716]: info: MYVOLUMIO: Evaluating Server Mar 12 17:39:23 rivoplus volumio[16716]: info: Setting Geolocation for MyVolumio to eu6 Mar 12 17:39:23 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:24 rivoplus volumio[16716]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 12 17:39:24 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:24 rivoplus volumio[16716]: info: Updating MyVolumio device info Mar 12 17:39:24 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:24 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:25 rivoplus volumio[16716]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:26 rivoplus mpd[16932]: exception: No database Mar 12 17:39:26 rivoplus volumio[16716]: error: MyVolumio Plugin failed to start in a timely fashion Mar 12 17:39:26 rivoplus volumio[16716]: [Metrics] CommandRouter: 41s 936.55ms Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::volumiosetStartupVolume Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:26 rivoplus volumio[16716]: info: VolumeController:: Setting startup Volume 40 Mar 12 17:39:26 rivoplus volumio[16716]: info: VolumeController::SetAlsaVolume40 Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::Close All Modals sent Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::Close All Modals sent Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreStateMachine::pushState Mar 12 17:39:26 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 17:39:26 rivoplus volumio[16716]: info: CoreCommandRouter::volumioPushState Mar 12 17:39:26 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:27 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 12 17:39:27 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 12 17:39:27 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:30 rivoplus mpd[16932]: exception: No database Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 17:39:30 rivoplus volumio-remote-updater[2859]: No test mode Mar 12 17:39:30 rivoplus volumio-remote-updater[2859]: No alpha test mode Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 12 17:39:30 rivoplus volumio[16716]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 17:39:30 rivoplus volumio[16716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 12 17:39:30 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetState Mar 12 17:39:30 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:33 rivoplus volumio[16716]: info: BOOT COMPLETED Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:34 rivoplus mpd[16932]: exception: No database Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 17:39:34 rivoplus volumio[16716]: info: Not Reporting Auto name since its the default one Mar 12 17:39:34 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 12 17:39:34 rivoplus sudo[17242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 12 17:39:34 rivoplus sudo[17242]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:34 rivoplus sudo[17242]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:34 rivoplus sudo[17244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 12 17:39:34 rivoplus sudo[17244]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 17:39:34 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 12 17:39:34 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 12 17:39:34 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 12 17:39:34 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 12 17:39:34 rivoplus systemd[1]: Started RAAT DAEMON. Mar 12 17:39:34 rivoplus sudo[17244]: pam_unix(sudo:session): session closed for user root Mar 12 17:39:34 rivoplus volumio[16716]: info: Raat Daemon started successfully Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::volumioGetBrowseSources Mar 12 17:39:35 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 17:39:36 rivoplus volumio[16716]: info: Executing endpoint restartRAATSocket Mar 12 17:39:36 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 12 17:39:36 rivoplus volumio[16716]: info: CorePlayQueue::getTrack 0 Mar 12 17:39:36 rivoplus volumio[16716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 12 17:39:37 rivoplus volumio[16716]: info: RAAT: Requesting Headphone Status Mar 12 17:39:37 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 12 17:39:37 rivoplus volumio[16716]: info: MCU Signalled Headphone Mode Disabled Mar 12 17:39:37 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 12 17:39:37 rivoplus volumio[16716]: info: Reporting Headphone State: false Mar 12 17:39:37 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:37 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 17:39:37 rivoplus volumio[16716]: info: Updating RAAT Signal Path Mar 12 17:39:38 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:38 rivoplus mpd[16932]: exception: No database Mar 12 17:39:38 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:39 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:41 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:41 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:42 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:42 rivoplus mpd[16932]: exception: No database Mar 12 17:39:43 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:43 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:46 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:46 rivoplus mpd[16932]: exception: No database Mar 12 17:39:50 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:50 rivoplus mpd[16932]: exception: No database Mar 12 17:39:50 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:51 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:51 rivoplus volumio[16716]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Mar 12 17:39:54 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:54 rivoplus mpd[16932]: exception: No database Mar 12 17:39:55 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:39:55 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:39:55 rivoplus volumio[16716]: info: Preloading song: upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I2827 Mar 12 17:39:55 rivoplus volumio[16716]: info: Preloading song: upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3083 Mar 12 17:39:55 rivoplus volumio[16716]: info: Preloading song: upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3339 Mar 12 17:39:55 rivoplus volumio[16716]: info: Preloading song: upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3595 Mar 12 17:39:55 rivoplus volumio[16716]: info: Preloading song: upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3851 Mar 12 17:39:55 rivoplus volumio[16716]: info: Exploding uri upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I2827 in service upnp_browser Mar 12 17:39:55 rivoplus volumio[16716]: info: Exploding uri upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3083 in service upnp_browser Mar 12 17:39:55 rivoplus volumio[16716]: info: Exploding uri upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3339 in service upnp_browser Mar 12 17:39:55 rivoplus volumio[16716]: info: Exploding uri upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3595 in service upnp_browser Mar 12 17:39:55 rivoplus volumio[16716]: info: Exploding uri upnp/http://192.168.1.126:9000/dev0/srv1/control@0$1$8I3851 in service upnp_browser Mar 12 17:39:58 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:39:58 rivoplus mpd[16932]: exception: No database Mar 12 17:40:02 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:40:02 rivoplus mpd[16932]: exception: No database Mar 12 17:40:05 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:40:05 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:40:06 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:40:06 rivoplus mpd[16932]: exception: No database Mar 12 17:40:09 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:40:10 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 17:40:10 rivoplus mpd[16932]: exception: No database Mar 12 17:40:12 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:40:14 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:40:14 rivoplus volumio[16716]: info: Preload queue cleared Mar 12 17:40:15 rivoplus volumio[16716]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 12 17:40:15 rivoplus volumio[16716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 17:40:15 rivoplus volumio[16716]: TypeError: Cannot read property 'length' of undefined Mar 12 17:40:15 rivoplus volumio[16716]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 12 17:40:15 rivoplus volumio[16716]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 12 17:40:15 rivoplus volumio[16716]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 12 17:40:15 rivoplus volumio[16716]: at Parser.emit (events.js:400:28) Mar 12 17:40:15 rivoplus volumio[16716]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 12 17:40:15 rivoplus volumio[16716]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 12 17:40:15 rivoplus volumio[16716]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 12 17:40:15 rivoplus volumio[16716]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 12 17:40:15 rivoplus volumio[16716]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 12 17:40:15 rivoplus volumio[16716]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 12 17:40:15 rivoplus volumio[16716]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 12 17:40:15 rivoplus volumio[16716]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 12 17:40:15 rivoplus volumio[16716]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 12 17:40:15 rivoplus volumio[16716]: at IncomingMessage.emit (events.js:412:35) Mar 12 17:40:15 rivoplus volumio[16716]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 12 17:40:15 rivoplus volumio[16716]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 12 17:40:15 rivoplus volumio[16716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 17:40:16 rivoplus sudo[17357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 17:39 Mar 12 17:40:16 rivoplus sudo[17357]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:29:52 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c26baf739f7d1f3fe648e6cf51b2c348"