-- Logs begin at Fri 2024-11-15 03:33:48 EET, end at Mon 2025-01-20 19:04:38 EET. -- Jan 20 19:03:53 rivo systemd-timedated[3424]: Changed local time to Mon Jan 20 19:03:53 2025 Jan 20 19:03:53 rivo sudo[3422]: pam_unix(sudo:session): session closed for user root Jan 20 19:03:53 rivo volumio-time-update[2846]: volumio-time-update-util: System time updated successfully. Jan 20 19:03:53 rivo systemd[1]: Started Volumio Time Update Utility. Jan 20 19:03:53 rivo systemd[1]: Starting Daily man-db regeneration... Jan 20 19:03:53 rivo systemd[1]: Reached target Multi-User System. Jan 20 19:03:53 rivo systemd[1]: Reached target Graphical Interface. Jan 20 19:03:53 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 20 19:03:53 rivo systemd[1]: Starting Daily apt download activities... Jan 20 19:03:53 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 20 19:03:53 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 20 19:03:53 rivo systemd[1]: man-db.service: Succeeded. Jan 20 19:03:53 rivo systemd[1]: Started Daily man-db regeneration. Jan 20 19:03:53 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Jan 20 19:03:53 rivo systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 20 19:03:53 rivo systemd[1]: Started Load/Save RF Kill Switch Status. Jan 20 19:03:53 rivo systemd[1]: Starting Bluetooth service... Jan 20 19:03:53 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:03:53 rivo volumio[3189]: info: ----- Volumio3 ---- Jan 20 19:03:53 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:03:53 rivo volumio[3189]: info: ----- System startup ---- Jan 20 19:03:53 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:03:53 rivo bluetoothd[3463]: Bluetooth daemon 5.50 Jan 20 19:03:53 rivo bluetoothd[3463]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 20 19:03:53 rivo systemd[1]: Started Bluetooth service. Jan 20 19:03:53 rivo systemd[1]: Reached target Bluetooth. Jan 20 19:03:53 rivo bluetoothd[3463]: Starting SDP server Jan 20 19:03:53 rivo bluetoothd[3463]: Excluding (cli) sap Jan 20 19:03:53 rivo dbus-daemon[2827]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=3463 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Jan 20 19:03:53 rivo bluetoothd[3463]: Bluetooth management interface 1.14 initialized Jan 20 19:03:53 rivo systemd[1]: Starting Hostname Service... Jan 20 19:03:53 rivo kernel: IPVS: Creating netns size=1928 id=2 Jan 20 19:03:53 rivo dbus-daemon[2827]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 20 19:03:53 rivo systemd[1]: Started Hostname Service. Jan 20 19:03:54 rivo volumio[3189]: info: MYVOLUMIO Environment detected Jan 20 19:03:55 rivo dhcpcd[3328]: wlan0: probing for an IPv4LL address Jan 20 19:03:55 rivo volumio[3189]: info: Plugin folders cleanup Jan 20 19:03:55 rivo volumio[3189]: info: Scanning into folder /volumio/app/plugins/ Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category audio_interface Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category miscellanea Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category music_service Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category plugins.json Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category system_controller Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category user_interface Jan 20 19:03:55 rivo volumio[3189]: info: Scanning into folder /data/plugins/ Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category audio_interface Jan 20 19:03:55 rivo volumio[3189]: info: Scanning category music_service Jan 20 19:03:55 rivo volumio[3189]: info: Plugin folders cleanup completed Jan 20 19:03:55 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:03:55 rivo volumio[3189]: info: ----- Core plugins startup ---- Jan 20 19:03:55 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:03:55 rivo volumio[3189]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin bluetooth to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin multiroom to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin upnp to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin metavolumio to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin cd_controller to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin raat to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 20 19:03:55 rivo volumio[3189]: info: Loading plugins from folder /data/plugins/ Jan 20 19:03:55 rivo volumio[3189]: info: Loading plugin "system"... Jan 20 19:03:55 rivo volumio[3189]: info: Loading plugin "appearance"... Jan 20 19:03:55 rivo systemd[1]: apt-daily.service: Succeeded. Jan 20 19:03:55 rivo systemd[1]: Started Daily apt download activities. Jan 20 19:03:55 rivo systemd[1]: Starting Daily apt upgrade and clean activities... Jan 20 19:03:57 rivo volumio-remote-updater[2824]: [2025-01-20 19:03:57] [connect] Successful connection Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "network"... Jan 20 19:03:57 rivo volumio[3189]: info: Refreshing Cached IP Addresses Jan 20 19:03:57 rivo sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 19:03:57 rivo sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:03:57 rivo sudo[3556]: pam_unix(sudo:session): session closed for user root Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "services"... Jan 20 19:03:57 rivo sudo[3559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 19:03:57 rivo sudo[3559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "alsa_controller"... Jan 20 19:03:57 rivo sudo[3559]: pam_unix(sudo:session): session closed for user root Jan 20 19:03:57 rivo sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 20 19:03:57 rivo sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:03:57 rivo kernel: wl_escan_set_scan: ioctl_ver=2 Jan 20 19:03:57 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "wizard"... Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "networkfs"... Jan 20 19:03:57 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 20 19:03:57 rivo systemd[1]: Started Daily apt upgrade and clean activities. Jan 20 19:03:57 rivo systemd[1]: Startup finished in 12.995s (kernel) + 19.077s (userspace) = 32.073s. Jan 20 19:03:57 rivo volumio[3189]: info: Starting Udev Watcher for removable devices Jan 20 19:03:57 rivo volumio[3189]: info: Ignoring mount for partition: BOOT Jan 20 19:03:57 rivo volumio[3189]: info: Ignoring mount for partition: volumio Jan 20 19:03:57 rivo volumio[3189]: info: Ignoring mount for partition: volumio_data Jan 20 19:03:57 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "volumio_command_line_client"... Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "upnp"... Jan 20 19:03:57 rivo volumio[3189]: info: [1737392637501] Starting Upmpd Daemon Jan 20 19:03:57 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "my_music"... Jan 20 19:03:57 rivo volumio[3189]: info: Loading plugin "mpd"... Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:57 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:58 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 8 Jan 20 19:03:58 rivo volumio[3189]: info: Loading plugin "upnp_browser"... Jan 20 19:03:58 rivo systemd[1]: systemd-rfkill.service: Succeeded. Jan 20 19:03:58 rivo volumio[3189]: info: Loading plugin "bluetooth"... Jan 20 19:03:58 rivo volumio[3189]: info: [1737392638871] Starting BluetoothController Jan 20 19:03:59 rivo volumio[3189]: info: Loading plugin "alarm-clock"... Jan 20 19:03:59 rivo volumio[3189]: info: Loading plugin "manifestui"... Jan 20 19:03:59 rivo volumio[3189]: info: Loading plugin "metavolumio"... Jan 20 19:03:59 rivo dhcpcd[3328]: wlan0: using IPv4LL address 169.254.160.178 Jan 20 19:03:59 rivo avahi-daemon[2831]: Registering new address record for 169.254.160.178 on wlan0.IPv4. Jan 20 19:03:59 rivo dhcpcd[3328]: wlan0: adding route to 169.254.0.0/16 Jan 20 19:04:00 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d6e9000 drv_status 0x0 e_type 69 e_states 0 Jan 20 19:04:00 rivo sudo[3569]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:01 rivo volumio[3189]: info: Loading plugin "airplay_emulation"... Jan 20 19:04:01 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:01 rivo volumio[3189]: info: Loading plugin "cd_controller"... Jan 20 19:04:01 rivo ntpd[3341]: Listen normally on 5 wlan0 169.254.160.178:123 Jan 20 19:04:01 rivo ntpd[3341]: new interface(s) found: waking up resolver Jan 20 19:04:01 rivo volumio[3189]: info: Loading plugin "last_100"... Jan 20 19:04:01 rivo volumio[3189]: info: Loading plugin "raat"... Jan 20 19:04:02 rivo volumio[3189]: info: RAAT Plugin loaded Jan 20 19:04:02 rivo volumio[3189]: info: Adding restartRAATSocket REST API Endpoint Jan 20 19:04:02 rivo volumio[3189]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jan 20 19:04:02 rivo volumio[3189]: info: Loading plugin "streaming_services"... Jan 20 19:04:04 rivo volumio[3189]: info: Starting Streaming Service Transparent Proxy Jan 20 19:04:04 rivo volumio[3189]: info: Loading plugin "tidalconnect"... Jan 20 19:04:04 rivo volumio[3189]: info: Loading plugin "webradio"... Jan 20 19:04:05 rivo volumio[3189]: info: Loading plugin "i2s_dacs"... Jan 20 19:04:05 rivo volumio[3189]: info: I2S DAC not set, start Auto-detection Jan 20 19:04:05 rivo volumio[3189]: info: Loading plugin "volumiodiscovery"... Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** For more information see Jan 20 19:04:05 rivo node[3189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 19:04:05 rivo volumio[3189]: *** WARNING *** For more information see Jan 20 19:04:05 rivo node[3189]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 19:04:05 rivo node[3189]: *** WARNING *** For more information see Jan 20 19:04:05 rivo node[3189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 19:04:05 rivo node[3189]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 19:04:05 rivo node[3189]: *** WARNING *** For more information see Jan 20 19:04:05 rivo volumio[3189]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 19:04:05 rivo volumio[3189]: info: Discovery: Started advertising with name: Rivo Jan 20 19:04:05 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:04:05 rivo volumio[3189]: info: Loading plugin "spop"... Jan 20 19:04:06 rivo volumio[3189]: STREAMING PROXY: Starting server on port 3245 Jan 20 19:04:06 rivo volumio[3189]: Node JS runtime: 14 Jan 20 19:04:07 rivo volumio[3189]: info: Loading plugin "youtube2"... Jan 20 19:04:09 rivo volumio[3189]: info: Loading plugin "ytcr"... Jan 20 19:04:11 rivo systemd[1]: systemd-fsckd.service: Succeeded. Jan 20 19:04:12 rivo volumio-remote-updater[2824]: [2025-01-20 19:04:12] [connect] Successful connection Jan 20 19:04:12 rivo volumio[3189]: info: Loading plugin "ytmusic"... Jan 20 19:04:13 rivo volumio[3189]: info: Loading plugin "multiroom"... Jan 20 19:04:15 rivo volumio[3189]: info: Applying required configuration parameters for plugin multiroom Jan 20 19:04:15 rivo sudo[3819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 20 19:04:15 rivo sudo[3819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:15 rivo sudo[3819]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:15 rivo volumio[3189]: info: MRS: MultiRoom plugin initialized Jan 20 19:04:15 rivo volumio[3189]: info: MRS: STOPPING SNAPCLIENT Jan 20 19:04:15 rivo volumio[3189]: info: MRS: Snap server stop Jan 20 19:04:15 rivo sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 20 19:04:15 rivo sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:15 rivo volumio[3189]: info: MRS: STOPPING volumioStreaming Jan 20 19:04:15 rivo sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 20 19:04:15 rivo sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:16 rivo sudo[3835]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:16 rivo sudo[3841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 20 19:04:16 rivo sudo[3841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:16 rivo volumio[3189]: info: Loading plugin "outputs"... Jan 20 19:04:16 rivo volumio[3189]: info: Loading plugin "albumart"... Jan 20 19:04:16 rivo sudo[3838]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:16 rivo sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 20 19:04:16 rivo sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:16 rivo sudo[3843]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:16 rivo volumio[3189]: info: Plugin example_plugin is not enabled Jan 20 19:04:16 rivo volumio[3189]: info: Loading plugin "hi_res_audio"... Jan 20 19:04:16 rivo sudo[3841]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:16 rivo volumio[3189]: Forking 3 albumart workers Jan 20 19:04:17 rivo volumio[3189]: Starting albumart workers Jan 20 19:04:17 rivo volumio[3189]: Starting albumart workers Jan 20 19:04:17 rivo volumio[3189]: Starting albumart workers Jan 20 19:04:17 rivo volumio[3189]: info: Applying required configuration parameters for plugin hi_res_audio Jan 20 19:04:17 rivo volumio[3189]: info: Loading plugin "inputs"... Jan 20 19:04:18 rivo volumio[3189]: info: Loading plugin "qobuz"... Jan 20 19:04:20 rivo volumio[3189]: info: Loading plugin "smart_inputs"... Jan 20 19:04:20 rivo volumio[3189]: info: Loading plugin "tidal"... Jan 20 19:04:22 rivo volumio[3189]: info: Loading plugin "rivocontrol"... Jan 20 19:04:23 rivo systemd[1]: systemd-timedated.service: Succeeded. Jan 20 19:04:23 rivo volumio[3189]: info: Adding this device properties Jan 20 19:04:23 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jan 20 19:04:23 rivo volumio[3189]: info: Setting Additional Device Volumio Properties: [object Object] Jan 20 19:04:23 rivo volumio[3189]: info: Loading plugin "updater_comm"... Jan 20 19:04:23 rivo volumio[3189]: info: Loading plugin "cec_controller"... Jan 20 19:04:23 rivo volumio[3189]: info: Applying required configuration parameters for plugin cec_controller Jan 20 19:04:23 rivo volumio[3189]: info: Plugin mpdemulation is not enabled Jan 20 19:04:23 rivo volumio[3189]: info: Loading plugin "rest_api"... Jan 20 19:04:23 rivo volumio[3189]: info: Loading plugin "websocket"... Jan 20 19:04:23 rivo volumio[3189]: info: Starting Socket.io Server version 2.3.0 Jan 20 19:04:23 rivo volumio[3189]: info: Loading plugin "fusiondsp"... Jan 20 19:04:24 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Jan 20 19:04:24 rivo volumio[3189]: info: Applying required configuration parameters for plugin fusiondsp Jan 20 19:04:24 rivo volumio[3189]: info: Loading i18n strings for locale en Jan 20 19:04:24 rivo volumio[3189]: Updating browse sources language Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::initPlayerControls Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:04:24 rivo volumio[3189]: Express server listening on port 3000 Jan 20 19:04:24 rivo volumio[3189]: [Metrics] WebUI: 32s 693.04ms Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::resetVolumioState Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::getcurrentVolume Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:25 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:25 rivo sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 19:04:25 rivo sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:25 rivo sudo[3927]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:25 rivo volumio[3189]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 20 19:04:25 rivo sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 19:04:25 rivo volumio[3189]: info: Completed loading Core Plugins Jan 20 19:04:25 rivo sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:25 rivo volumio[3189]: info: Preparing to generate the ALSA configuration file Jan 20 19:04:25 rivo sudo[3931]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:25 rivo volumio[3189]: info: Volumio Network Manager: Network status updated: 1 Jan 20 19:04:25 rivo volumio[3189]: info: MRS: Removed streaming files Jan 20 19:04:25 rivo volumio[3189]: info: MRS: volumioStreaming STOPPED Jan 20 19:04:25 rivo volumio[3189]: info: MRS: SNAPSERVER STOPPED Jan 20 19:04:25 rivo volumio[3189]: info: MRS: SNAPCLIENT STOPPED Jan 20 19:04:25 rivo volumio[3189]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 20 19:04:25 rivo volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 20 19:04:25 rivo volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 20 19:04:25 rivo volumio[3189]: info: Reading ALSA contributions from plugins. Jan 20 19:04:25 rivo volumio[3189]: info: Reloading queue from file Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::setRepeat false single undefined Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:25 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::setRandom undefined Jan 20 19:04:25 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:25 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:25 rivo sudo[3941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 19:04:25 rivo sudo[3941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:25 rivo volumio[3189]: info: Asound.conf file unchanged, so no further update is needed Jan 20 19:04:25 rivo volumio[3189]: info: Output device has changed, restarting MPD Jan 20 19:04:25 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 19:04:25 rivo sudo[3941]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:25 rivo sudo[3944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:04:25 rivo sudo[3944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:25 rivo sudo[3944]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:25 rivo volumio[3189]: info: Output device has changed, restarting Shairport Sync Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:25 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:25 rivo sudo[3957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:04:25 rivo sudo[3957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:25 rivo volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:04:25 rivo systemd[1]: Stopping Music Player Daemon... Jan 20 19:04:25 rivo volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: ___________ START PLUGINS ___________ Jan 20 19:04:26 rivo volumio[3189]: info: ControllerMpd::onStart: Initializing MPD Jan 20 19:04:26 rivo volumio[3189]: info: Creating MPD Configuration file Jan 20 19:04:26 rivo sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:04:26 rivo sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 19:04:26 rivo volumio[3189]: info: [1737392666139] CoreMusicLibrary::Adding element Media Servers Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:26 rivo sudo[3965]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:26 rivo systemd[1]: mpd.service: Succeeded. Jan 20 19:04:26 rivo systemd[1]: Stopped Music Player Daemon. Jan 20 19:04:26 rivo sudo[3967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:04:26 rivo sudo[3967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo systemd[1]: Starting Music Player Daemon... Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:04:26 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 20 19:04:26 rivo systemd[1]: mpd.service: Succeeded. Jan 20 19:04:26 rivo systemd[1]: Stopped Music Player Daemon. Jan 20 19:04:26 rivo systemd[1]: Starting Music Player Daemon... Jan 20 19:04:26 rivo volumio[3189]: info: Adding METAVOLUMIO REST API Endpoints Jan 20 19:04:26 rivo volumio[3189]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 20 19:04:26 rivo volumio[3189]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 20 19:04:26 rivo volumio[3189]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 20 19:04:26 rivo volumio[3189]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:26 rivo sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Jan 20 19:04:26 rivo sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo sudo[3972]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:26 rivo volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:26 rivo sudo[3973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 19:04:26 rivo sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:04:26 rivo volumio[3189]: info: Preparing CD Folders Jan 20 19:04:26 rivo sudo[3973]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:26 rivo volumio[3189]: info: Adding CD REST API Endpoints Jan 20 19:04:26 rivo volumio[3189]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 20 19:04:26 rivo volumio[3189]: info: Starting UDEV Watcher for CD Jan 20 19:04:26 rivo volumio[3189]: info: Detecting CD presence with UDEV Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 19:04:26 rivo volumio[3189]: info: [1737392666451] CoreMusicLibrary::Adding element Last_100 Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:26 rivo volumio[3189]: info: Starting RAAT Plugin Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 20 19:04:26 rivo volumio[3189]: info: Additional UI Settings Added for plugin music_service/raat Jan 20 19:04:26 rivo volumio[3189]: info: Registering DSP Elements listener and retrieving current ones Jan 20 19:04:26 rivo volumio[3189]: info: Additional DSP elements updated Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: Updating RAAT Signal Path Jan 20 19:04:26 rivo volumio[3189]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 20 19:04:26 rivo volumio[3189]: info: Streaming services startup Jan 20 19:04:26 rivo volumio[3189]: info: Starting Streaming Daemon Jan 20 19:04:26 rivo sudo[3986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 20 19:04:26 rivo sudo[3986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo sudo[3986]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 19:04:26 rivo volumio[3189]: info: [1737392666674] CoreMusicLibrary::Adding element Webradio Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 19:04:26 rivo volumio[3189]: info: Initializing BBC Radios Jan 20 19:04:26 rivo sudo[3994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 20 19:04:26 rivo sudo[3994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:26 rivo sudo[3994]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: Creating Spotify config file Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 19:04:26 rivo volumio[3189]: info: [1737392666870] CoreMusicLibrary::Adding element YouTube2 Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:26 rivo volumio[3189]: Cannot find translation for source YouTube2 Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 19:04:26 rivo volumio[3189]: info: [1737392666954] CoreMusicLibrary::Adding element YouTube Music Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:26 rivo volumio[3189]: Cannot find translation for source YouTube2 Jan 20 19:04:26 rivo volumio[3189]: Cannot find translation for source YouTube Music Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 20 19:04:26 rivo volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:04:26 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:27 rivo volumio[3189]: error: Hi Res Audio Failed Login: Missing Login Data Jan 20 19:04:27 rivo volumio[3189]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 20 19:04:27 rivo volumio[3189]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 20 19:04:27 rivo volumio[3189]: info: Initializing Serial Communication on port /dev/ttyS3 Jan 20 19:04:27 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jan 20 19:04:27 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jan 20 19:04:27 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jan 20 19:04:27 rivo volumio[3189]: info: Touch Event Listener Process Starting Jan 20 19:04:27 rivo volumio-remote-updater[2824]: [2025-01-20 19:04:27] [connect] Successful connection Jan 20 19:04:27 rivo volumio[3189]: info: Adding inputs REST Endpoints Jan 20 19:04:27 rivo volumio[3189]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 20 19:04:27 rivo volumio[3189]: info: Scanning Audio Inputs Jan 20 19:04:27 rivo sudo[4011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jan 20 19:04:27 rivo sudo[4011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo volumio[3189]: info: Checking against Known Cards name Jan 20 19:04:27 rivo volumio[3189]: info: Checking against Known Cards name Jan 20 19:04:27 rivo volumio[3189]: info: Checking against Known Cards name Jan 20 19:04:27 rivo volumio[3189]: info: Checking against Known Cards name Jan 20 19:04:27 rivo volumio[3189]: info: Checking against Known Cards name Jan 20 19:04:27 rivo volumio[3189]: info: Adding Server instance for streaming Jan 20 19:04:27 rivo sudo[4022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jan 20 19:04:27 rivo sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo sudo[4022]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:27 rivo sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jan 20 19:04:27 rivo sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo sudo[4025]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:27 rivo sudo[4030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jan 20 19:04:27 rivo sudo[4030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo sudo[4030]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:27 rivo sudo[4033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jan 20 19:04:27 rivo sudo[4033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo sudo[4033]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:27 rivo volumio[3189]: info: Apply VIM3L Onboard LEDs Settings Jan 20 19:04:27 rivo volumio[3189]: info: Turning On Onboard LEDs Jan 20 19:04:27 rivo sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jan 20 19:04:27 rivo sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo volumio[3189]: info: Starting CEC Event listener Jan 20 19:04:27 rivo kernel: fb: mem_free_work, free memory: addr:800000 Jan 20 19:04:27 rivo sudo[4041]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:27 rivo volumio[3189]: info: Loading i18n strings for locale en Jan 20 19:04:27 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 20 19:04:27 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:27 rivo volumio[3189]: info: FusionDsp - mixtype--------------------- None Jan 20 19:04:27 rivo sudo[4051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jan 20 19:04:27 rivo volumio[3189]: info: Volumio Calling Home Jan 20 19:04:27 rivo sudo[4051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:27 rivo sudo[4051]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:28 rivo volumio[3189]: (node:3189) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 20 19:04:28 rivo volumio[3189]: (Use `node --trace-deprecation ...` to show where the warning was created) Jan 20 19:04:28 rivo volumio[3189]: info: Preparing to generate the ALSA configuration file Jan 20 19:04:28 rivo volumio[3189]: info: Stopping AccessToken refresher cron for QOBUZ Jan 20 19:04:28 rivo volumio[3189]: info: AccessToken refresher cron started for QOBUZ Jan 20 19:04:28 rivo volumio[3189]: info: Stopping AccessToken refresher cron Jan 20 19:04:28 rivo volumio[3189]: info: AccessToken refresher cron started Jan 20 19:04:28 rivo volumio[3189]: info: Adding TIDAL REST API Endpoints Jan 20 19:04:28 rivo volumio[3189]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 20 19:04:28 rivo volumio[3189]: info: Adding QOBUZ REST API Endpoints Jan 20 19:04:28 rivo volumio[3189]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 20 19:04:28 rivo volumio[3189]: info: Discovery: adding 8083edce-1957-44f2-bc94-1ca4d62c41d7 Jan 20 19:04:28 rivo volumio[3189]: info: Discovery: Found device Rivo Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output for this device Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output Jan 20 19:04:28 rivo volumio[3189]: info: Adding audio output: Jan 20 19:04:28 rivo volumio[3189]: info: Adding audio output: Jan 20 19:04:28 rivo volumio[3189]: info: Discovery: this is already registered, 8083edce-1957-44f2-bc94-1ca4d62c41d7 Jan 20 19:04:28 rivo volumio[3189]: info: Discovery: Found device Rivo Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: Serial port opened successfully Jan 20 19:04:28 rivo volumio[3189]: info: Sending serial start messages Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:28 rivo volumio[3189]: info: Reporting MCU Network Status: 1 Jan 20 19:04:28 rivo volumio[3189]: info: MP1 GPIO: Signalled system ready via GPIO Jan 20 19:04:28 rivo volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 20 19:04:28 rivo volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 20 19:04:28 rivo volumio[3189]: info: Reading ALSA contributions from plugins. Jan 20 19:04:28 rivo volumio[3189]: error: Cannot start Volumio Streaming Daemon Jan 20 19:04:28 rivo volumio[3189]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 20 19:04:28 rivo volumio[3189]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 20 19:04:28 rivo volumio[3189]: info: RAAT Albumart path created successfully Jan 20 19:04:28 rivo volumio[3189]: info: Bluetooth name changed to Rivo Jan 20 19:04:28 rivo volumio[3189]: info: MPD Permissions set Jan 20 19:04:28 rivo volumio[3189]: info: MPD Permissions set Jan 20 19:04:28 rivo volumio[3189]: info: Upmpdcli Daemon Started Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 20 19:04:28 rivo volumio[3189]: info: Setting Device Volume Override Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 20 19:04:28 rivo volumio[3189]: info: Updating Volume Controller Parameters: Device: 5 Name: XMOS Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 20 19:04:28 rivo volumio[3189]: info: Disabling external Volume Control Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:28 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:04:28 rivo volumio[3189]: info: MRS: Pushing multiroomSync output Jan 20 19:04:28 rivo volumio-remote-updater[2824]: [2025-01-20 19:04:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737392667 101 Jan 20 19:04:28 rivo volumio[3189]: 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: 1 Jan 20 19:04:28 rivo volumio[3189]: info: Volumio called home Jan 20 19:04:28 rivo volumio[3189]: info: Spotify config file written Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 20 19:04:28 rivo volumio[3189]: info: Setting Additional System Software info: Hardware Revision: 2.0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 20 19:04:28 rivo volumio[3189]: info: Setting HW Firmware info: undefined Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 20 19:04:28 rivo volumio[3189]: info: Setting HW Version info: 2.0 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 20 19:04:28 rivo volumio[3189]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.4 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 20 19:04:28 rivo volumio[3189]: info: Setting HW Firmware info: 0.3.4 Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 20 19:04:28 rivo volumio[3189]: info: Setting HW Version info: 2.0 Jan 20 19:04:28 rivo volumio[3189]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 20 19:04:28 rivo volumio[3189]: info: MCU Signalled Headphone Mode Disabled Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 20 19:04:28 rivo volumio[3189]: info: Reporting Headphone State: false Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:28 rivo volumio[3189]: info: Updating RAAT Signal Path Jan 20 19:04:28 rivo volumio[3189]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 20 19:04:28 rivo volumio[3189]: info: MCU Signalled Sleep Mode Disabled Jan 20 19:04:28 rivo sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 20 19:04:28 rivo sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:28 rivo volumio[3189]: info: Enabling Advanced system settings configuration Jan 20 19:04:28 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jan 20 19:04:28 rivo volumio[3189]: info: Additional UI Settings Added for plugin music_service/inputs Jan 20 19:04:28 rivo volumio[3189]: info: MCU Signalled Auto Boot Mode On Power Disabled Jan 20 19:04:28 rivo systemd[1]: Started go-librespot Daemon. Jan 20 19:04:28 rivo sudo[4080]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:28 rivo sudo[4083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jan 20 19:04:28 rivo sudo[4083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:28 rivo kernel: fb: osd[0] enable: 1 (Xorg) Jan 20 19:04:29 rivo go-librespot[4085]: Librespot-go daemon starting... Jan 20 19:04:29 rivo sudo[4083]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 20 19:04:29 rivo go-librespot[4085]: time="2025-01-20T19:04:29+02:00" level=info msg="generated new device id: 802bb612d933f7e1f37347deba75946806a10e6a" Jan 20 19:04:29 rivo mpd[3982]: Jan 20 19:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 19:04:29 rivo systemd[1]: Started Music Player Daemon. Jan 20 19:04:29 rivo sudo[3957]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo sudo[3967]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jan 20 19:04:29 rivo volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 20 19:04:29 rivo sudo[4111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jan 20 19:04:29 rivo sudo[4111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:29 rivo sudo[4111]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo sudo[4114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 20 19:04:29 rivo sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:29 rivo systemd[1]: Started Volumio Bluetooth Module. Jan 20 19:04:29 rivo sudo[4114]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo sudo[4117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 20 19:04:29 rivo sudo[4117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:29 rivo volumio[3189]: Upnp client error: Error: This socket has been ended by the other party Jan 20 19:04:29 rivo volumiobt[4116]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 20 19:04:29 rivo sudo[4117]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:29 rivo go-librespot[4085]: time="2025-01-20T19:04:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 20 19:04:29 rivo go-librespot[4085]: time="2025-01-20T19:04:29+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 20 19:04:29 rivo go-librespot[4085]: time="2025-01-20T19:04:29+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 20 19:04:29 rivo go-librespot[4085]: time="2025-01-20T19:04:29+02:00" level=debug msg="zeroconf server listening on port 42503" Jan 20 19:04:29 rivo volumio[3189]: info: Volumio BT Module successfully started Jan 20 19:04:29 rivo volumio[3189]: info: camilladsp spawned new process with pid 4130, instance 1, run: true Jan 20 19:04:29 rivo volumio[3189]: info: camilladsp service started and running in background, instance 1 Jan 20 19:04:29 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:29 rivo volumio[3189]: No protocol specified Jan 20 19:04:29 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:29 rivo volumio[3189]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jan 20 19:04:30 rivo volumio[3189]: info: FusionDsp loaded Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:04:30 rivo sudo[4140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 20 19:04:30 rivo sudo[4140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 20 19:04:30 rivo volumio[3189]: info: Adding Signal Path Element [object Object] Jan 20 19:04:30 rivo volumio[3189]: info: Adding fusiondspeq DSP Signal Path Element Jan 20 19:04:30 rivo volumio[3189]: info: Additional DSP elements updated Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:30 rivo volumio[3189]: info: Updating RAAT Signal Path Jan 20 19:04:30 rivo volumio[3189]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 20 19:04:30 rivo volumio[3189]: info: FusionDsp - ---- installed callbackRead Jan 20 19:04:30 rivo volumio[3189]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 20 19:04:30 rivo systemd[1]: Started FusionDsp Daemon. Jan 20 19:04:30 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:30 rivo sudo[4140]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:30 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:30 rivo sudo[4146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:04:30 rivo sudo[4146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:30 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:04:30 rivo systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:04:30 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:30 rivo sudo[4149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:04:30 rivo sudo[4149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: Adding Inputs via Serial API Jan 20 19:04:30 rivo volumio[3189]: info: MRS: Found cast device: BRAVIA-4K-GB-ATV3-dcf5165d2e8b1272333fd4658d76b8ff Jan 20 19:04:30 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:30 rivo sudo[4146]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:30 rivo volumio[3189]: info: Adding audio output: Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:30 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:30 rivo volumio[3189]: info: CoreStateMachine::pushState Jan 20 19:04:30 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::volumioPushState Jan 20 19:04:30 rivo sudo[4151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:04:30 rivo sudo[4151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:04:30 rivo volumio[3189]: info: MRS: Pushing multiroomSync output Jan 20 19:04:30 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:04:30 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 20 19:04:30 rivo systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:04:30 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:30 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:30 rivo sudo[4149]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:30 rivo sudo[4151]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:30 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:30 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:30 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:30 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:30 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:30 rivo volumio[3189]: error: MPD error: The expression evaluated to a falsy value: Jan 20 19:04:30 rivo volumio[3189]: assert.ok(self.idling) Jan 20 19:04:30 rivo volumio[3189]: error: The expression evaluated to a falsy value: Jan 20 19:04:30 rivo volumio[3189]: assert.ok(self.idling) Jan 20 19:04:30 rivo sudo[4161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 20 19:04:30 rivo sudo[4161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:30 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:30 rivo volumiobt[4116]: pulseaudio: no process found Jan 20 19:04:30 rivo sudo[4161]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:30 rivo volumio[3189]: info: TidalConnect service stoped! Jan 20 19:04:30 rivo volumio[3189]: info: MPD running with PID3982 Jan 20 19:04:30 rivo volumio[3189]: ,establishing connection Jan 20 19:04:30 rivo volumio[3189]: info: Turning Off Onboard LEDs Jan 20 19:04:30 rivo dbus-daemon[2827]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.24' (uid=1000 pid=4170 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::servicePushState Jan 20 19:04:30 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:30 rivo volumio[3189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received inputs Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 20 19:04:30 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:04:30 rivo volumio[3189]: Cannot find translation for source YouTube2 Jan 20 19:04:30 rivo volumio[3189]: Cannot find translation for source YouTube Music Jan 20 19:04:30 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 20 19:04:30 rivo kernel: IPVS: Creating netns size=1928 id=3 Jan 20 19:04:30 rivo sudo[4172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jan 20 19:04:30 rivo sudo[4172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:30 rivo volumio[3189]: info: MRS: Found cast device: BRAVIA-4K-VH2-3f715479eafac42d119369508a6f1d28 Jan 20 19:04:30 rivo volumio[3189]: info: Adding audio output: Jan 20 19:04:30 rivo sudo[4172]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:31 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:31 rivo dbus-daemon[2827]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 20 19:04:31 rivo rtkit-daemon[4173]: Successfully called chroot. Jan 20 19:04:31 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 20 19:04:31 rivo rtkit-daemon[4173]: Successfully dropped privileges. Jan 20 19:04:31 rivo rtkit-daemon[4173]: Successfully limited resources. Jan 20 19:04:31 rivo rtkit-daemon[4173]: Running. Jan 20 19:04:31 rivo volumio[3189]: error: updateQueue error: null Jan 20 19:04:31 rivo dbus-daemon[2827]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.25' (uid=0 pid=4173 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jan 20 19:04:31 rivo rtkit-daemon[4173]: Canary thread running. Jan 20 19:04:31 rivo rtkit-daemon[4173]: Watchdog thread running. Jan 20 19:04:31 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:31 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:31 rivo systemd[1]: Starting Authorization Manager... Jan 20 19:04:31 rivo sudo[4176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jan 20 19:04:31 rivo sudo[4176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:31 rivo sudo[4176]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:31 rivo volumio[3189]: info: Shairport-Sync Started Jan 20 19:04:31 rivo volumio[3189]: Error adding Membership: Error: addMembership EINVAL Jan 20 19:04:31 rivo volumio[3189]: info: Shairport-Sync Started Jan 20 19:04:31 rivo volumio[3189]: info: Shairport-Sync Started Jan 20 19:04:31 rivo polkitd[4179]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 20 19:04:31 rivo dbus-daemon[2827]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 20 19:04:31 rivo systemd[1]: Started Authorization Manager. Jan 20 19:04:31 rivo volumio[3189]: info: Asound.conf file unchanged, so no further update is needed Jan 20 19:04:31 rivo volumio[3189]: info: Output device has changed, restarting MPD Jan 20 19:04:31 rivo sudo[4190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:04:31 rivo sudo[4190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:31 rivo volumio[3189]: info: Output device has changed, restarting Shairport Sync Jan 20 19:04:31 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:31 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:31 rivo sudo[4190]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:31 rivo sudo[4193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:04:31 rivo sudo[4193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:31 rivo systemd[1]: Stopping Music Player Daemon... Jan 20 19:04:31 rivo volumio[3189]: No protocol specified Jan 20 19:04:31 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:31 rivo volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:04:31 rivo volumio[3189]: No protocol specified Jan 20 19:04:31 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:31 rivo volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:04:31 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:31 rivo systemd[1]: mpd.service: Succeeded. Jan 20 19:04:31 rivo systemd[1]: Stopped Music Player Daemon. Jan 20 19:04:31 rivo volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 20 19:04:31 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:31 rivo systemd[1]: Starting Music Player Daemon... Jan 20 19:04:31 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:31 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:31 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:31 rivo sudo[4200]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 19:04:31 rivo sudo[4200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:31 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:31 rivo sudo[4200]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:31 rivo volumio[3189]: error: MPD error: Error: write EPIPE Jan 20 19:04:31 rivo volumio[3189]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 20 19:04:32 rivo volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 20 19:04:32 rivo pulseaudio[4170]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 20 19:04:32 rivo pulseaudio[4170]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 20 19:04:32 rivo volumiobt[4116]: Applying permissions Jan 20 19:04:32 rivo volumiobt[4116]: Setting BT discoverable and pairable Jan 20 19:04:32 rivo bluetoothd[3463]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSource Jan 20 19:04:32 rivo bluetoothd[3463]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSink Jan 20 19:04:32 rivo pulseaudio[4170]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 20 19:04:32 rivo volumio[3189]: info: Onboard LEDs initialized Jan 20 19:04:32 rivo volumio[3189]: info: MRS: Getting audio outputs on start Jan 20 19:04:32 rivo volumio[3189]: info: MRS: Requesting all other devices output Jan 20 19:04:32 rivo volumiobt[4116]: Agent registered Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# -e power on Jan 20 19:04:32 rivo volumiobt[4116]: Invalid command in menu main: -e Jan 20 19:04:32 rivo volumiobt[4116]: Jan 20 19:04:32 rivo volumiobt[4116]: Use "help" for a list of available commands in a menu. Jan 20 19:04:32 rivo volumiobt[4116]: Use "menu " if you want to enter any submenu. Jan 20 19:04:32 rivo volumiobt[4116]: Use "back" if you want to return to menu main. Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# agent on Jan 20 19:04:32 rivo volumiobt[4116]: Agent is already registered Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# discoverable on Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# pairable on Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# agent NoInputNoOutput Jan 20 19:04:32 rivo volumiobt[4116]: Agent is already registered Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# default-agent Jan 20 19:04:32 rivo volumiobt[4116]: [bluetooth]# quit Jan 20 19:04:32 rivo volumiobt[4116]: [59B blob data] Jan 20 19:04:32 rivo volumio[3189]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 20 19:04:32 rivo volumio[3189]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 20 19:04:32 rivo volumio[3189]: info: MPD Permissions set Jan 20 19:04:32 rivo volumio[3189]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 20 19:04:32 rivo volumio[3189]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 20 19:04:32 rivo sudo[4211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 20 19:04:32 rivo sudo[4211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: Not Reporting Auto name since its the default one Jan 20 19:04:32 rivo volumio[3189]: info: go-librespot daemon successfully initialized Jan 20 19:04:32 rivo systemd[1]: Started Volumio Tidal Connect Service. Jan 20 19:04:32 rivo volumio[3189]: info: MCU Signalled Playback Inactive Jan 20 19:04:32 rivo sudo[4211]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:32 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 20 19:04:33 rivo volumio[3189]: No protocol specified Jan 20 19:04:33 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:33 rivo sudo[4229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 20 19:04:33 rivo sudo[4229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:33 rivo volumio[3189]: 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 20 19:04:33 rivo sudo[4229]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:33 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:33 rivo sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 20 19:04:33 rivo sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:33 rivo volumio[3189]: info: Preparing to generate the ALSA configuration file Jan 20 19:04:33 rivo sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:04:33 rivo sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:33 rivo volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 20 19:04:33 rivo volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 20 19:04:33 rivo volumio[3189]: info: Reading ALSA contributions from plugins. Jan 20 19:04:33 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:04:33 rivo systemd[1]: Started RAAT DAEMON. Jan 20 19:04:33 rivo sudo[4231]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:33 rivo systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:04:33 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:33 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:33 rivo sudo[4234]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:33 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:33 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:33 rivo volumio[3189]: info: Raat Daemon started successfully Jan 20 19:04:33 rivo volumio[3189]: info: Executing endpoint tc_getconfig Jan 20 19:04:33 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 20 19:04:33 rivo vtcs[4217]: STARTING TidalConnect services, version: 1.4.0.34 Jan 20 19:04:33 rivo volumio[3189]: info: Executing endpoint restartRAATSocket Jan 20 19:04:33 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 20 19:04:33 rivo volumio[3189]: info: Shairport-Sync Started Jan 20 19:04:33 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:33 rivo volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 20 19:04:34 rivo volumio[3189]: info: Asound.conf file written Jan 20 19:04:34 rivo go-librespot[4085]: time="2025-01-20T19:04:34+02:00" level=debug msg="obtained new client token: AAAhmNJfcSw/7ER/St7i2Os0uN33RIHmKuMuIS4qasZrUjEAQSBRmYLqXAly9V8nwDFD97KzKidITr3Y057Iw2pEfXCC2IvtYCCM71IZovDfeBU8gQjQT08K5evh70uuchDUh6vaMS2ocWMWnRzl8CmU0oWYRK+PS4HrzHYJw5IndQsYZa0b/y/PVPgTkZ29/l1BMi1WBDjYlyuQmsTz/6e+KJYViMgG2LQO/pXu1cz0SegFCSAqtoIlM5Yeshc=" Jan 20 19:04:34 rivo sudo[4252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 20 19:04:34 rivo sudo[4252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:34 rivo vtcs[4217]: STARTED TidalConnect services. Jan 20 19:04:34 rivo sudo[4252]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:34 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Jan 20 19:04:34 rivo kernel: Err: can not set rate to 4278967296! Jan 20 19:04:34 rivo kernel: Range[3920000 - 500000000] Jan 20 19:04:34 rivo kernel: out of value, fixed it Jan 20 19:04:34 rivo kernel: id=0 set inskew=0 Jan 20 19:04:34 rivo volumio[3189]: No protocol specified Jan 20 19:04:34 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:34 rivo volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jan 20 19:04:34 rivo volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jan 20 19:04:34 rivo volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jan 20 19:04:34 rivo volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jan 20 19:04:34 rivo volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:XMOS Internal Clock Validity:0' : Operation not permitted Jan 20 19:04:34 rivo volumio[3189]: info: Output device has changed, restarting MPD Jan 20 19:04:34 rivo go-librespot[4085]: time="2025-01-20T19:04:34+02:00" level=debug msg="completed keyexchange" Jan 20 19:04:34 rivo volumio[3189]: info: Output device has changed, restarting Shairport Sync Jan 20 19:04:34 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:34 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:34 rivo sudo[4265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:04:34 rivo sudo[4265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:34 rivo sudo[4266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:04:34 rivo sudo[4266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:34 rivo sudo[4265]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:34 rivo volumio[3189]: No protocol specified Jan 20 19:04:34 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:34 rivo volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:04:34 rivo volumio[3189]: No protocol specified Jan 20 19:04:34 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:34 rivo volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:04:34 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:34 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 20 19:04:34 rivo systemd[1]: mpd.service: Succeeded. Jan 20 19:04:34 rivo systemd[1]: Stopped Music Player Daemon. Jan 20 19:04:34 rivo volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 20 19:04:34 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:34 rivo volumio[3189]: 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:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jan 20 19:04:34 rivo systemd[1]: Starting Music Player Daemon... Jan 20 19:04:34 rivo sudo[4277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 20 19:04:34 rivo sudo[4277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:34 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:34 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:34 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:34 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:34 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Jan 20 19:04:34 rivo go-librespot[4085]: time="2025-01-20T19:04:34+02:00" level=debug msg="completed challenge" Jan 20 19:04:34 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 20 19:04:35 rivo systemd[1]: vtcs.service: Succeeded. Jan 20 19:04:35 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Jan 20 19:04:35 rivo volumio[3189]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 20 19:04:35 rivo volumio[3189]: info: MPD Permissions set Jan 20 19:04:35 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:35 rivo sudo[4277]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 19:04:35 rivo sudo[4276]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 19:04:35 rivo sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:35 rivo volumio[3189]: info: Executing endpoint tc_connect Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="authenticated as omt2r1rhuqabrpr1l9vm1aicg" Jan 20 19:04:35 rivo volumio[3189]: info: Connecting to TidalConnect Jan 20 19:04:35 rivo sudo[4276]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="authenticated as omt2r1rhuqabrpr1l9vm1aicg" Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:04:35 rivo volumio[3189]: info: Not Reporting Auto name since its the default one Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="dealer connection opened" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=info msg="accepted zeroconf user omt2r1rhuqabrpr1l9vm1aicg from Galaxy A52s 5G" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="received connection id: MzlkYWMxN2EtOGJkZi00OGI4LWJlZTktNjA3OGY1YTZlYjhkK2RlYWxlcit0Y3A6Ly8wYWNhNDA4ZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMjgxQUMwQUZCNjZCRjc0NzQ0QjlDQzYzOTdFQUY5MDgwMjIxMUExQzRBRDZFMzdGQzAyMkYwNDRFRTQwRTk5NQ==" Jan 20 19:04:35 rivo volumio[3189]: info: Initializing connection to go-librespot Websocket Jan 20 19:04:35 rivo volumio[3189]: info: Starting Shairport Sync Jan 20 19:04:35 rivo volumio[3189]: info: TidalConnect service started! Jan 20 19:04:35 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="new websocket client" Jan 20 19:04:35 rivo sudo[4293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:04:35 rivo sudo[4293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:35 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:04:35 rivo systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:04:35 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:35 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:04:35 rivo sudo[4293]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:35 rivo go-librespot[4085]: time="2025-01-20T19:04:35+02:00" level=debug msg="put connect state because NEW_DEVICE" Jan 20 19:04:35 rivo volumio[3189]: No protocol specified Jan 20 19:04:35 rivo volumio[3189]: xcb_connection_has_error() returned true Jan 20 19:04:35 rivo volumio[3189]: info: Connection to go-librespot Websocket established Jan 20 19:04:35 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:35 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:35 rivo sudo[4299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 20 19:04:35 rivo volumio[3189]: info: Shairport-Sync Started Jan 20 19:04:35 rivo sudo[4299]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:35 rivo sudo[4299]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="handling transfer player command from efcfab949dbda6c3e098e7c35d488ebf6b534016" Jan 20 19:04:36 rivo sudo[4303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 20 19:04:36 rivo sudo[4303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=trace msg="fetched new page 0 with 191 items (list: 191)" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="loading track spotify:track:2RTkSxbiq0j5ztqyGpAmKW (paused: false, position: 5104ms)" Jan 20 19:04:36 rivo systemd[1]: Stopping RAAT DAEMON... Jan 20 19:04:36 rivo volumio[3189]: info: RAAT: Requesting Headphone Status Jan 20 19:04:36 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 20 19:04:36 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 20 19:04:36 rivo systemd[1]: raat-daemon.service: Succeeded. Jan 20 19:04:36 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:04:36 rivo systemd[1]: Stopped RAAT DAEMON. Jan 20 19:04:36 rivo volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 20 19:04:36 rivo systemd[1]: Started RAAT DAEMON. Jan 20 19:04:36 rivo sudo[4303]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:36 rivo volumio[3189]: info: Raat Daemon started successfully Jan 20 19:04:36 rivo volumio[3189]: info: MCU Signalled Headphone Mode Disabled Jan 20 19:04:36 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 20 19:04:36 rivo volumio[3189]: info: Reporting Headphone State: false Jan 20 19:04:36 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:04:36 rivo volumio[3189]: info: Updating RAAT Signal Path Jan 20 19:04:36 rivo volumio[3189]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=trace msg="emitting websocket event: will_play" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2RTkSxbiq0j5ztqyGpAmKW" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="requested aes key for file ab9ef6b137090ebfdfacef2dfcbbbfcb83bc1bf6, gid: 2RTkSxbiq0j5ztqyGpAmKW" Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1363" Jan 20 19:04:36 rivo volumio[3189]: info: Completed starting Core Plugins Jan 20 19:04:36 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:04:36 rivo volumio[3189]: info: ----- MyVolumio plugins startup ---- Jan 20 19:04:36 rivo volumio[3189]: info: ------------------------------------------- Jan 20 19:04:36 rivo volumio[3189]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 20 19:04:36 rivo volumio[3189]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2RTkSxbiq0j5ztqyGpAmKW","play_origin":"playlist"}} Jan 20 19:04:36 rivo volumio[3189]: info: Executing endpoint restartRAATSocket Jan 20 19:04:36 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 20 19:04:36 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:36 rivo volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 20 19:04:36 rivo go-librespot[4085]: time="2025-01-20T19:04:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1126" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="fetched first chunk of 25, total size is 12682872 bytes" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=trace msg="seek to 5104ms (diff: 95ms, samples: 225086, bytes: 359345)" Jan 20 19:04:37 rivo volumio[3189]: info: FusionDsp - ---- read samplerate, raw: Jan 20 19:04:37 rivo volumio[3189]: error: FusionDsp - invalid sample rate Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=info msg="loaded track \"Sexbomb\" (uri: spotify:track:2RTkSxbiq0j5ztqyGpAmKW, paused: false, position: 5104ms, duration: 287146ms)" Jan 20 19:04:37 rivo volumio[3189]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 20 19:04:37 rivo volumio[3189]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 20 19:04:37 rivo volumio[3189]: info: camilladsp stopping service pid 4130... Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="fetched chunk 2/24, size: 524288" Jan 20 19:04:37 rivo volumio[3189]: info: camilladsp service terminated, instance 1 Jan 20 19:04:37 rivo volumio[3189]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 20 19:04:37 rivo volumio[3189]: info: camilladsp service started and running in background, instance 1 Jan 20 19:04:37 rivo volumio[3189]: 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 20 19:04:37 rivo volumio[3189]: info: Checking for updated MCU Firmware Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=trace msg="emitting websocket event: metadata" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=trace msg="emitting websocket event: active" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="sending successful reply for delaer request" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 19:04:37 rivo volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="fetched chunk 3/24, size: 524288" Jan 20 19:04:37 rivo volumio[3189]: info: Firware on device is on latest version, no need to update Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="fetched chunk 1/24, size: 524288" Jan 20 19:04:37 rivo volumio[3189]: info: camilladsp spawned new process with pid 4319, instance 1, run: true Jan 20 19:04:37 rivo volumio[3189]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2RTkSxbiq0j5ztqyGpAmKW","name":"Sexbomb","artist_names":["Helene Fischer","Tom Jones"],"album_name":"Sexbomb","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0296072452d9df5d569297c8c7","position":5104,"duration":287146,"release_date":"year:2020 month:11 day:27","track_number":1,"disc_number":1}} Jan 20 19:04:37 rivo volumio[3189]: SPOTIFY: received: {"type":"active","data":null} Jan 20 19:04:37 rivo volumio[3189]: info: Aligning Spotify Volume to Volumio Volume Jan 20 19:04:37 rivo volumio[3189]: info: CoreCommandRouter::volumioGetState Jan 20 19:04:37 rivo volumio[3189]: info: CorePlayQueue::getTrack 0 Jan 20 19:04:37 rivo volumio[3189]: info: Setting Spotify Volume from Volumio: 100 Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 19:04:37 rivo go-librespot[4085]: time="2025-01-20T19:04:37+02:00" level=trace msg="emitting websocket event: playing" Jan 20 19:04:37 rivo volumio[3189]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2RTkSxbiq0j5ztqyGpAmKW","play_origin":"playlist"}} Jan 20 19:04:37 rivo volumio[3189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 19:04:37 rivo volumio[3189]: TypeError: Cannot read property 'service' of undefined Jan 20 19:04:37 rivo volumio[3189]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jan 20 19:04:37 rivo volumio[3189]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Jan 20 19:04:37 rivo volumio[3189]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jan 20 19:04:37 rivo volumio[3189]: at WebSocket.emit (events.js:400:28) Jan 20 19:04:37 rivo volumio[3189]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Jan 20 19:04:37 rivo volumio[3189]: at Receiver.emit (events.js:400:28) Jan 20 19:04:37 rivo volumio[3189]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Jan 20 19:04:37 rivo volumio[3189]: at internal/process/task_queues.js:141:7 Jan 20 19:04:37 rivo volumio[3189]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jan 20 19:04:37 rivo volumio[3189]: at AsyncResource.runMicrotask (internal/process/task_queues.js:138:8) Jan 20 19:04:37 rivo volumio[3189]: at runMicrotasks () Jan 20 19:04:37 rivo volumio[3189]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 19:04:37 rivo volumio[3189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 19:04:38 rivo mpd[4281]: Jan 20 19:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 19:04:38 rivo systemd[1]: Started Music Player Daemon. Jan 20 19:04:38 rivo sudo[4266]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:38 rivo sudo[4193]: pam_unix(sudo:session): session closed for user root Jan 20 19:04:38 rivo go-librespot[4085]: time="2025-01-20T19:04:38+02:00" level=debug msg="fetched chunk 4/24, size: 524288" Jan 20 19:04:38 rivo sudo[4354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 19:03 Jan 20 19:04:38 rivo sudo[4354]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"