-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-06-07 20:56:16 CEST. -- Jun 07 20:55:12 rivo.rick systemd-timedated[3498]: Changed local time to Sat Jun 7 20:55:12 2025 Jun 07 20:55:12 rivo.rick sudo[3487]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:12 rivo.rick volumio-time-update[2843]: volumio-time-update-util: System time updated successfully. Jun 07 20:55:12 rivo.rick systemd[1]: Started Volumio Time Update Utility. Jun 07 20:55:12 rivo.rick systemd[1]: Starting Daily apt download activities... Jun 07 20:55:12 rivo.rick systemd[1]: Starting Daily man-db regeneration... Jun 07 20:55:12 rivo.rick systemd[1]: man-db.service: Succeeded. Jun 07 20:55:12 rivo.rick systemd[1]: Started Daily man-db regeneration. Jun 07 20:55:12 rivo.rick systemd[1]: systemd-rfkill.service: Succeeded. Jun 07 20:55:12 rivo.rick ntpd[3497]: Soliciting pool server 195.95.153.59 Jun 07 20:55:13 rivo.rick ntpd[3497]: Soliciting pool server 162.159.200.123 Jun 07 20:55:13 rivo.rick ntpd[3497]: Soliciting pool server 89.140.186.3 Jun 07 20:55:13 rivo.rick kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Jun 07 20:55:13 rivo.rick systemd[1]: Started Samba NMB Daemon. Jun 07 20:55:13 rivo.rick nmbd[3072]: [2025/06/07 20:55:13.781239, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 20:55:13 rivo.rick nmbd[3072]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 07 20:55:13 rivo.rick systemd[1]: Starting Samba Winbind Daemon... Jun 07 20:55:14 rivo.rick winbindd[3599]: [2025/06/07 20:55:14.405626, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 07 20:55:14 rivo.rick winbindd[3599]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 07 20:55:14 rivo.rick systemd[1]: Started Samba Winbind Daemon. Jun 07 20:55:14 rivo.rick systemd[1]: Starting Samba SMB Daemon... Jun 07 20:55:14 rivo.rick winbindd[3599]: [2025/06/07 20:55:14.434364, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 20:55:14 rivo.rick winbindd[3599]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 07 20:55:14 rivo.rick volumio[3128]: info: Starting Streaming Service Transparent Proxy Jun 07 20:55:14 rivo.rick volumio[3128]: info: Loading plugin "tidalconnect"... Jun 07 20:55:14 rivo.rick ntpd[3497]: Soliciting pool server 90.68.206.60 Jun 07 20:55:14 rivo.rick ntpd[3497]: Soliciting pool server 162.159.200.1 Jun 07 20:55:14 rivo.rick ntpd[3497]: Soliciting pool server 212.227.232.46 Jun 07 20:55:14 rivo.rick volumio-remote-updater[2841]: [2025-06-07 20:55:14] [connect] Successful connection Jun 07 20:55:14 rivo.rick volumio[3128]: info: Loading plugin "webradio"... Jun 07 20:55:15 rivo.rick volumio[3128]: info: Loading plugin "i2s_dacs"... Jun 07 20:55:15 rivo.rick volumio[3128]: info: I2S DAC not set, start Auto-detection Jun 07 20:55:15 rivo.rick volumio[3128]: info: Loading plugin "volumiodiscovery"... Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** For more information see Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 20:55:15 rivo.rick volumio[3128]: *** WARNING *** For more information see Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** For more information see Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 07 20:55:15 rivo.rick volumio[3128]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 07 20:55:15 rivo.rick node[3128]: *** WARNING *** For more information see Jun 07 20:55:15 rivo.rick volumio[3128]: info: Discovery: Started advertising with name: Rivo.rick Jun 07 20:55:15 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 07 20:55:15 rivo.rick volumio[3128]: info: Loading plugin "spop"... Jun 07 20:55:15 rivo.rick smbd[3619]: [2025/06/07 20:55:15.581542, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 07 20:55:15 rivo.rick systemd[1]: Started Samba SMB Daemon. Jun 07 20:55:15 rivo.rick smbd[3619]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 07 20:55:15 rivo.rick systemd[1]: Reached target Multi-User System. Jun 07 20:55:15 rivo.rick systemd[1]: Reached target Graphical Interface. Jun 07 20:55:15 rivo.rick systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 07 20:55:15 rivo.rick systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 07 20:55:15 rivo.rick systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 07 20:55:15 rivo.rick ntpd[3497]: Soliciting pool server 94.143.139.219 Jun 07 20:55:15 rivo.rick ntpd[3497]: Soliciting pool server 94.143.139.219 Jun 07 20:55:15 rivo.rick ntpd[3497]: Soliciting pool server 94.143.139.219 Jun 07 20:55:15 rivo.rick ntpd[3497]: Soliciting pool server 195.20.235.143 Jun 07 20:55:15 rivo.rick systemd[1]: apt-daily.service: Succeeded. Jun 07 20:55:15 rivo.rick systemd[1]: Started Daily apt download activities. Jun 07 20:55:15 rivo.rick systemd[1]: Starting Daily apt upgrade and clean activities... Jun 07 20:55:16 rivo.rick ntpd[3497]: Soliciting pool server 83.97.79.109 Jun 07 20:55:16 rivo.rick ntpd[3497]: Soliciting pool server 5.250.191.170 Jun 07 20:55:17 rivo.rick volumio[3128]: STREAMING PROXY: Starting server on port 3245 Jun 07 20:55:17 rivo.rick volumio[3128]: Node JS runtime: 14 Jun 07 20:55:17 rivo.rick ntpd[3497]: Soliciting pool server 84.77.195.114 Jun 07 20:55:17 rivo.rick ntpd[3497]: Soliciting pool server 5.250.184.159 Jun 07 20:55:18 rivo.rick volumio[3128]: info: Plugin multiroom is not enabled Jun 07 20:55:18 rivo.rick volumio[3128]: info: Loading plugin "outputs"... Jun 07 20:55:18 rivo.rick volumio[3128]: info: Loading plugin "albumart"... Jun 07 20:55:18 rivo.rick volumio[3128]: info: Plugin example_plugin is not enabled Jun 07 20:55:18 rivo.rick volumio[3128]: info: Loading plugin "hi_res_audio"... Jun 07 20:55:18 rivo.rick systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 07 20:55:18 rivo.rick systemd[1]: Started Daily apt upgrade and clean activities. Jun 07 20:55:18 rivo.rick systemd[1]: Startup finished in 13.065s (kernel) + 26.587s (userspace) = 39.653s. Jun 07 20:55:19 rivo.rick volumio[3128]: Forking 3 albumart workers Jun 07 20:55:20 rivo.rick volumio[3128]: Starting albumart workers Jun 07 20:55:20 rivo.rick volumio[3128]: Starting albumart workers Jun 07 20:55:20 rivo.rick volumio[3128]: Starting albumart workers Jun 07 20:55:21 rivo.rick volumio[3128]: info: Applying required configuration parameters for plugin hi_res_audio Jun 07 20:55:21 rivo.rick volumio[3128]: info: Loading plugin "inputs"... Jun 07 20:55:22 rivo.rick ntpd[3497]: receive: Unexpected origin timestamp 0xebef0999.bd785b6b does not match aorg 0000000000.00000000 from server@83.97.79.109 xmt 0xebef099a.75d8db3c Jun 07 20:55:22 rivo.rick ntpd[3497]: receive: Unexpected origin timestamp 0xebef0999.bd86871b does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebef099a.759d0e8a Jun 07 20:55:22 rivo.rick ntpd[3497]: receive: Unexpected origin timestamp 0xebef0999.bd81465f does not match aorg 0000000000.00000000 from server@212.227.232.46 xmt 0xebef099a.76633e5e Jun 07 20:55:22 rivo.rick ntpd[3497]: receive: Unexpected origin timestamp 0xebef0999.bd840c22 does not match aorg 0000000000.00000000 from server@89.140.186.3 xmt 0xebef099a.7765a8e2 Jun 07 20:55:22 rivo.rick volumio[3128]: info: Loading plugin "qobuz"... Jun 07 20:55:24 rivo.rick volumio[3128]: info: Loading plugin "smart_inputs"... Jun 07 20:55:24 rivo.rick volumio[3128]: info: Loading plugin "tidal"... Jun 07 20:55:26 rivo.rick systemd[1]: systemd-fsckd.service: Succeeded. Jun 07 20:55:26 rivo.rick volumio[3128]: info: Loading plugin "rivocontrol"... Jun 07 20:55:27 rivo.rick volumio[3128]: info: Adding this device properties Jun 07 20:55:27 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jun 07 20:55:27 rivo.rick volumio[3128]: info: Setting Additional Device Volumio Properties: [object Object] Jun 07 20:55:27 rivo.rick volumio[3128]: info: Loading plugin "updater_comm"... Jun 07 20:55:27 rivo.rick volumio[3128]: info: Loading plugin "cec_controller"... Jun 07 20:55:27 rivo.rick volumio[3128]: info: Applying required configuration parameters for plugin cec_controller Jun 07 20:55:27 rivo.rick volumio[3128]: info: Plugin mpdemulation is not enabled Jun 07 20:55:27 rivo.rick volumio[3128]: info: Loading plugin "rest_api"... Jun 07 20:55:27 rivo.rick volumio[3128]: info: Loading plugin "websocket"... Jun 07 20:55:27 rivo.rick volumio[3128]: info: Starting Socket.io Server version 2.3.0 Jun 07 20:55:27 rivo.rick volumio[3128]: info: Plugin fusiondsp is not enabled Jun 07 20:55:27 rivo.rick volumio[3128]: info: Loading i18n strings for locale es Jun 07 20:55:27 rivo.rick volumio[3128]: Updating browse sources language Jun 07 20:55:27 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::initPlayerControls Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: Express server listening on port 3000 Jun 07 20:55:28 rivo.rick volumio[3128]: [Metrics] WebUI: 26s 440.46ms Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreStateMachine::resetVolumioState Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreStateMachine::getcurrentVolume Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:28 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:28 rivo.rick sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 20:55:28 rivo.rick sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick sudo[3809]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:28 rivo.rick volumio[3128]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 07 20:55:28 rivo.rick volumio[3128]: info: Completed loading Core Plugins Jun 07 20:55:28 rivo.rick volumio[3128]: info: Preparing to generate the ALSA configuration file Jun 07 20:55:28 rivo.rick sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 20:55:28 rivo.rick sudo[3811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick sudo[3811]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:28 rivo.rick volumio[3128]: info: Volumio Network Manager: Network status updated: 0 Jun 07 20:55:28 rivo.rick volumio[3128]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 07 20:55:28 rivo.rick volumio[3128]: info: Reloading queue from file Jun 07 20:55:28 rivo.rick volumio[3128]: info: Asound.conf file unchanged, so no further update is needed Jun 07 20:55:28 rivo.rick volumio[3128]: info: Output device has changed, restarting MPD Jun 07 20:55:28 rivo.rick volumio[3128]: info: Output device has changed, restarting Shairport Sync Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:28 rivo.rick sudo[3822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 20:55:28 rivo.rick sudo[3822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick sudo[3824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 20:55:28 rivo.rick sudo[3824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick sudo[3822]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:28 rivo.rick systemd[1]: Stopping Music Player Daemon... Jun 07 20:55:28 rivo.rick volumio[3128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 20:55:28 rivo.rick volumio[3128]: info: ___________ START PLUGINS ___________ Jun 07 20:55:28 rivo.rick volumio[3128]: info: ControllerMpd::onStart: Initializing MPD Jun 07 20:55:28 rivo.rick volumio[3128]: info: Creating MPD Configuration file Jun 07 20:55:28 rivo.rick sudo[3831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 20:55:28 rivo.rick sudo[3831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick sudo[3831]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 20:55:28 rivo.rick volumio[3128]: info: [1749322528966] CoreMusicLibrary::Adding element Media Servers Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:28 rivo.rick sudo[3834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 20:55:28 rivo.rick sudo[3834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:28 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding METAVOLUMIO REST API Endpoints Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:29 rivo.rick systemd[1]: mpd.service: Succeeded. Jun 07 20:55:29 rivo.rick systemd[1]: Stopped Music Player Daemon. Jun 07 20:55:29 rivo.rick sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo.rick Jun 07 20:55:29 rivo.rick sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick systemd[1]: Starting Music Player Daemon... Jun 07 20:55:29 rivo.rick sudo[3837]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick volumio[3128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 20:55:29 rivo.rick volumio[3128]: info: Preparing CD Folders Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding CD REST API Endpoints Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 07 20:55:29 rivo.rick volumio[3128]: info: Starting UDEV Watcher for CD Jun 07 20:55:29 rivo.rick volumio[3128]: info: Detecting CD presence with UDEV Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 07 20:55:29 rivo.rick sudo[3840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 20:55:29 rivo.rick sudo[3840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick sudo[3840]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 20:55:29 rivo.rick volumio[3128]: info: [1749322529223] CoreMusicLibrary::Adding element Last_100 Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 07 20:55:29 rivo.rick volumio[3128]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 07 20:55:29 rivo.rick volumio[3128]: info: Starting RAAT Plugin Jun 07 20:55:29 rivo.rick sudo[3858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 07 20:55:29 rivo.rick sudo[3858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jun 07 20:55:29 rivo.rick volumio[3128]: info: Additional UI Settings Added for plugin music_service/raat Jun 07 20:55:29 rivo.rick volumio[3128]: info: Registering DSP Elements listener and retrieving current ones Jun 07 20:55:29 rivo.rick volumio[3128]: info: Additional DSP elements updated Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: Updating RAAT Signal Path Jun 07 20:55:29 rivo.rick volumio[3128]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jun 07 20:55:29 rivo.rick volumio[3128]: info: Streaming services startup Jun 07 20:55:29 rivo.rick volumio[3128]: info: Starting Streaming Daemon Jun 07 20:55:29 rivo.rick sudo[3858]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick sudo[3862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 07 20:55:29 rivo.rick sudo[3862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 20:55:29 rivo.rick sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick systemd[1]: Started Volumio Qobuz Connect Service. Jun 07 20:55:29 rivo.rick sudo[3862]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick sudo[3864]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 20:55:29 rivo.rick volumio[3128]: info: [1749322529473] CoreMusicLibrary::Adding element Webradio Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:55:29 rivo.rick volumio[3128]: info: Initializing BBC Radios Jun 07 20:55:29 rivo.rick sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 20:55:29 rivo.rick sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick sudo[3875]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: Creating Spotify config file Jun 07 20:55:29 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 07 20:55:29 rivo.rick volumio[3128]: info: Initializing Serial Communication on port /dev/ttyS3 Jun 07 20:55:29 rivo.rick kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jun 07 20:55:29 rivo.rick kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jun 07 20:55:29 rivo.rick kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jun 07 20:55:29 rivo.rick volumio[3128]: info: Touch Event Listener Process Starting Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding inputs REST Endpoints Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 07 20:55:29 rivo.rick volumio[3128]: info: Scanning Audio Inputs Jun 07 20:55:29 rivo.rick sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jun 07 20:55:29 rivo.rick sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick volumio[3128]: info: Checking against Known Cards name Jun 07 20:55:29 rivo.rick volumio[3128]: info: Checking against Known Cards name Jun 07 20:55:29 rivo.rick volumio[3128]: info: Checking against Known Cards name Jun 07 20:55:29 rivo.rick volumio[3128]: info: Checking against Known Cards name Jun 07 20:55:29 rivo.rick volumio[3128]: info: Checking against Known Cards name Jun 07 20:55:29 rivo.rick volumio[3128]: info: Adding Server instance for streaming Jun 07 20:55:29 rivo.rick volumio[3128]: info: Refreshing TIDAL token Jun 07 20:55:29 rivo.rick sudo[3901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jun 07 20:55:29 rivo.rick sudo[3901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:29 rivo.rick sudo[3901]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick sudo[3904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jun 07 20:55:30 rivo.rick sudo[3904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick sudo[3904]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick sudo[3907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jun 07 20:55:30 rivo.rick sudo[3907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick sudo[3907]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick sudo[3910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jun 07 20:55:30 rivo.rick sudo[3910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick sudo[3910]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick volumio[3128]: info: Apply VIM3L Onboard LEDs Settings Jun 07 20:55:30 rivo.rick volumio[3128]: info: Turning On Onboard LEDs Jun 07 20:55:30 rivo.rick sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jun 07 20:55:30 rivo.rick sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick volumio[3128]: info: Starting CEC Event listener Jun 07 20:55:30 rivo.rick volumio[3128]: info: Volumio Calling Home Jun 07 20:55:30 rivo.rick sudo[3914]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jun 07 20:55:30 rivo.rick sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick sudo[3918]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick volumio[3128]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 07 20:55:30 rivo.rick volumio[3128]: (node:3128) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 07 20:55:30 rivo.rick volumio[3128]: (Use `node --trace-deprecation ...` to show where the warning was created) Jun 07 20:55:30 rivo.rick volumio[3128]: info: Stopping AccessToken refresher cron for QOBUZ Jun 07 20:55:30 rivo.rick volumio[3128]: info: AccessToken refresher cron started for QOBUZ Jun 07 20:55:30 rivo.rick volumio[3128]: info: Adding QOBUZ REST API Endpoints Jun 07 20:55:30 rivo.rick volumio[3128]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreStateMachine::setRepeat false single undefined Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreStateMachine::setRandom null Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:30 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:30 rivo.rick volumio[3128]: info: Serial port opened successfully Jun 07 20:55:30 rivo.rick volumio[3128]: info: Sending serial start messages Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:30 rivo.rick volumio[3128]: info: Reporting MCU Network Status: 0 Jun 07 20:55:30 rivo.rick volumio[3128]: info: MP1 GPIO: Signalled system ready via GPIO Jun 07 20:55:30 rivo.rick volumio-remote-updater[2841]: [2025-06-07 20:55:30] [connect] Successful connection Jun 07 20:55:30 rivo.rick volumio[3128]: error: Cannot start Volumio Streaming Daemon Jun 07 20:55:30 rivo.rick volumio[3128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 20:55:30 rivo.rick volumio[3128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 20:55:30 rivo.rick volumio[3128]: info: RAAT Albumart path created successfully Jun 07 20:55:30 rivo.rick volumio[3128]: info: Bluetooth name changed to Rivo.rick Jun 07 20:55:30 rivo.rick volumio[3128]: info: MPD Permissions set Jun 07 20:55:30 rivo.rick volumio[3128]: info: MPD Permissions set Jun 07 20:55:30 rivo.rick sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 07 20:55:30 rivo.rick sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:30 rivo.rick systemd[1]: Started UPnP Renderer front-end to MPD. Jun 07 20:55:30 rivo.rick sudo[3926]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:30 rivo.rick volumio[3128]: info: Executing endpoint qc_getconfig Jun 07 20:55:30 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 07 20:55:30 rivo.rick qobuz-connect[3870]: 20250607 20:55:30.827 [3870.3870] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 07 20:55:30 rivo.rick volumio[3128]: info: Volumio called home Jun 07 20:55:30 rivo.rick volumio[3128]: info: Spotify config file written Jun 07 20:55:30 rivo.rick sudo[3930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 07 20:55:30 rivo.rick sudo[3930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.014 [3870.3870] INFO VolumeManager: [0xac853908]: Setting new playback volume: 75 Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.015 [3870.3870] INFO VolumeManager: [0xac853908]: Setting new mute state: 0 Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.015 [3870.3870] INFO QobuzConnect: [0xac8542d8]: Client initialized! Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.015 [3870.3870] INFO SampleApp: Starting Avahi advertising, name: Rivo.rick, service name: _qobuz-connect._tcp Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.041 [3870.3870] INFO LocalConfigManager: [0xac8531e8]: Starting Local Configuration server Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.042 [3870.3870] INFO SampleApp: Starting Local configuration server Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.042 [3870.3870] INFO SampleApp: Playback volume changed: 75 Jun 07 20:55:31 rivo.rick qobuz-connect[3870]: 20250607 20:55:31.043 [3870.3870] INFO SampleApp: Connected to UNIX socket client 0xac848ed8 Jun 07 20:55:31 rivo.rick systemd[1]: Started go-librespot Daemon. Jun 07 20:55:31 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 07 20:55:31 rivo.rick sudo[3930]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:31 rivo.rick volumio[3128]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 07 20:55:31 rivo.rick go-librespot[3933]: go-librespot daemon starting... Jun 07 20:55:31 rivo.rick volumio-remote-updater[2841]: [2025-06-07 20:55:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749322530 101 Jun 07 20:55:31 rivo.rick volumio[3128]: 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: 2 Jun 07 20:55:31 rivo.rick volumio[3128]: info: Upmpdcli Daemon Started Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=info msg="running go-librespot 0.2.0" Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=debug msg="app state loaded" Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: No need to fix Spotify hosts Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting Device Volume Override Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 20:55:31 rivo.rick volumio[3128]: info: Updating Volume Controller Parameters: Device: 5 Name: DAC-R26 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 20:55:31 rivo.rick volumio[3128]: info: Disabling external Volume Control Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting Additional System Software info: Hardware Revision: 2.1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting HW Firmware info: undefined Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting HW Version info: 2.1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.4 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting HW Firmware info: 0.3.4 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jun 07 20:55:31 rivo.rick volumio[3128]: info: Setting HW Version info: 2.1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 07 20:55:31 rivo.rick volumio[3128]: info: MCU Signalled Headphone Mode Disabled Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 07 20:55:31 rivo.rick volumio[3128]: info: Reporting Headphone State: false Jun 07 20:55:31 rivo.rick mpd[3854]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:31 rivo.rick volumio[3128]: info: Updating RAAT Signal Path Jun 07 20:55:31 rivo.rick mpd[3854]: output: No 'audio_output' defined in config file Jun 07 20:55:31 rivo.rick volumio[3128]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jun 07 20:55:31 rivo.rick volumio[3128]: info: MCU Signalled Sleep Mode Disabled Jun 07 20:55:31 rivo.rick volumio[3128]: info: Enabling Advanced system settings configuration Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jun 07 20:55:31 rivo.rick volumio[3128]: info: Additional UI Settings Added for plugin music_service/inputs Jun 07 20:55:31 rivo.rick volumio[3128]: info: MCU Signalled Auto Boot Mode On Power Disabled Jun 07 20:55:31 rivo.rick kernel: aml_tdm_open Jun 07 20:55:31 rivo.rick kernel: Not init audio effects Jun 07 20:55:31 rivo.rick kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 07 20:55:31 rivo.rick kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jun 07 20:55:31 rivo.rick kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2f4c18, id(1), clksel(1) Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_tdm_fmt(), fmt not change Jun 07 20:55:31 rivo.rick kernel: dump_pcm_setting(ffffffc03d2f4c18) Jun 07 20:55:31 rivo.rick kernel: pcm_mode(1) Jun 07 20:55:31 rivo.rick kernel: sysclk(12288000) Jun 07 20:55:31 rivo.rick kernel: sysclk_bclk_ratio(4) Jun 07 20:55:31 rivo.rick kernel: bclk(3072000) Jun 07 20:55:31 rivo.rick kernel: bclk_lrclk_ratio(64) Jun 07 20:55:31 rivo.rick kernel: lrclk(48000) Jun 07 20:55:31 rivo.rick kernel: tx_mask(0x3) Jun 07 20:55:31 rivo.rick kernel: rx_mask(0x3) Jun 07 20:55:31 rivo.rick kernel: slots(2) Jun 07 20:55:31 rivo.rick kernel: slot_width(32) Jun 07 20:55:31 rivo.rick kernel: lane_mask_in(0x2) Jun 07 20:55:31 rivo.rick kernel: lane_mask_out(0x1) Jun 07 20:55:31 rivo.rick kernel: lane_oe_mask_in(0x0) Jun 07 20:55:31 rivo.rick kernel: lane_oe_mask_out(0x0) Jun 07 20:55:31 rivo.rick kernel: lane_lb_mask_in(0x0) Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 07 20:55:31 rivo.rick kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jun 07 20:55:31 rivo.rick kernel: aml_dai_set_bclk_ratio, select I2S mode Jun 07 20:55:31 rivo.rick kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jun 07 20:55:31 rivo.rick kernel: aml_tdm_prepare(), reset fddr Jun 07 20:55:31 rivo.rick kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jun 07 20:55:31 rivo.rick kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Jun 07 20:55:31 rivo.rick kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 20:55:31 rivo.rick kernel: tdm playback mute: 0, lane_cnt = 8 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:31 rivo.rick mpd[3854]: output: Successfully detected a sndio audio device Jun 07 20:55:31 rivo.rick kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 07 20:55:31 rivo.rick kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jun 07 20:55:31 rivo.rick kernel: tdm playback mute: 1, lane_cnt = 8 Jun 07 20:55:31 rivo.rick kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:31 rivo.rick mpd[3854]: zeroconf: No global port, disabling zeroconf Jun 07 20:55:31 rivo.rick systemd[1]: Started Music Player Daemon. Jun 07 20:55:31 rivo.rick sudo[3834]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:31 rivo.rick sudo[3824]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:31 rivo.rick sudo[3959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jun 07 20:55:31 rivo.rick sudo[3959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:31 rivo.rick kernel: fb: osd[0] enable: 1 (Xorg) Jun 07 20:55:31 rivo.rick sudo[3959]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:31 rivo.rick volumio[3128]: info: Access Token successfully retrieved Jun 07 20:55:31 rivo.rick volumio[3128]: error: Hi Res Audio Failed Login: [object Object] Jun 07 20:55:31 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 07 20:55:31 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:31 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:31 rivo.rick volumio[3128]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+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-gae2.spotify.com:80]" Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 07 20:55:31 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:31+02:00" level=info msg="zeroconf server listening on port 39775" Jun 07 20:55:31 rivo.rick sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jun 07 20:55:31 rivo.rick sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick sudo[3965]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick volumio[3128]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 07 20:55:32 rivo.rick volumio[3128]: SPOTIFY: BQBVzqdw2TdRn8LG5jju481gurrE5iBJACHMB0KIBGoECaeClIf3FLSoIt37ASLu3LWHc3jLBl_A2SVwL7Q3E16Fx_AN_yCJRd2XHt3LPkNq4Hcp2wN5ySarOvWz0yU2u91trDCz7h1WlrY6481iq_nTcMREGx9QIAudZiapPiQhwWcOISX64OXHSDzsHICfwUvTjR0tx9XmV4tZMssA4_pZ_6Gtm0n5ee1Ipzavboj3KsWggmpGpdzAlmMb-C-RBPfNxMY-hyigR29-AWjAfUcFRTFZ_g Jun 07 20:55:32 rivo.rick volumio[3128]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 07 20:55:32 rivo.rick volumio[3128]: info: New Spotify access token = BQBVzqdw2TdRn8LG5jju481gurrE5iBJACHMB0KIBGoECaeClIf3FLSoIt37ASLu3LWHc3jLBl_A2SVwL7Q3E16Fx_AN_yCJRd2XHt3LPkNq4Hcp2wN5ySarOvWz0yU2u91trDCz7h1WlrY6481iq_nTcMREGx9QIAudZiapPiQhwWcOISX64OXHSDzsHICfwUvTjR0tx9XmV4tZMssA4_pZ_6Gtm0n5ee1Ipzavboj3KsWggmpGpdzAlmMb-C-RBPfNxMY-hyigR29-AWjAfUcFRTFZ_g Jun 07 20:55:32 rivo.rick volumio[3128]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="obtained new client token: AABqQpdvPhMqayB/ilMrkKwkS0EJIkH5xgIgwQMCxHip7b5vcutMdeAQyk+EC7Q+B4clN++d+lvDWNQ5320+C1jqns71EWOlamg1VxDPl3HNoOstI3eQ48kSQN393kn9pH1Z8aSqjZkNelcSnuRzvkTe1761DNisoFwIyQhjqRz00UF392wwg0kWV+pOIIxuHy0eLzn+aMYGkuPYurPgwK1UI4qUWg8jvBdqh4ux+chRF4SRCGwFkQ==" Jun 07 20:55:32 rivo.rick volumio[3128]: info: Starting Shairport Sync Jun 07 20:55:32 rivo.rick sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 07 20:55:32 rivo.rick sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jun 07 20:55:32 rivo.rick volumio[3128]: info: Starting Shairport Sync Jun 07 20:55:32 rivo.rick systemd[1]: Started Volumio Bluetooth Module. Jun 07 20:55:32 rivo.rick sudo[3974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 20:55:32 rivo.rick sudo[3974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick volumio[3128]: info: Starting Shairport Sync Jun 07 20:55:32 rivo.rick sudo[3971]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="completed keyexchange" Jun 07 20:55:32 rivo.rick sudo[3979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="completed challenge" Jun 07 20:55:32 rivo.rick sudo[3979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 20:55:32 rivo.rick sudo[3980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 07 20:55:32 rivo.rick sudo[3980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick systemd[1]: shairport-sync.service: Succeeded. Jun 07 20:55:32 rivo.rick systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick sudo[3974]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick volumiobt[3976]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 07 20:55:32 rivo.rick sudo[3980]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick sudo[3984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 20:55:32 rivo.rick sudo[3984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 20:55:32 rivo.rick systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 07 20:55:32 rivo.rick systemd[1]: shairport-sync.service: Succeeded. Jun 07 20:55:32 rivo.rick systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=info msg="authenticated AP as montana20052001" Jun 07 20:55:32 rivo.rick systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick sudo[3979]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 20:55:32 rivo.rick systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 07 20:55:32 rivo.rick systemd[1]: shairport-sync.service: Succeeded. Jun 07 20:55:32 rivo.rick systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 20:55:32 rivo.rick sudo[3984]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick volumio[3128]: error: MPD error: The expression evaluated to a falsy value: Jun 07 20:55:32 rivo.rick volumio[3128]: assert.ok(self.idling) Jun 07 20:55:32 rivo.rick volumio[3128]: error: The expression evaluated to a falsy value: Jun 07 20:55:32 rivo.rick volumio[3128]: assert.ok(self.idling) Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=info msg="authenticated Login5 as montana20052001" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="initializing zeroconf session, username: montana20052001" Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:32 rivo.rick volumio[3128]: info: MPD running with PID3854 Jun 07 20:55:32 rivo.rick volumio[3128]: ,establishing connection Jun 07 20:55:32 rivo.rick volumio[3128]: info: Adding Inputs via Serial API Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:32 rivo.rick volumio[3128]: error: updateQueue error: null Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="dealer connection opened" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=trace msg="starting accesspoint recv loop" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=trace msg="starting dealer recv loop" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=trace msg="received accesspoint ping" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="received connection id: ZGY2Nzc0N2YtNjc3Ni00M2I3LTllNGItNWE1MzhjN2JjYjkzK2RlYWxlcit0Y3A6Ly8wYWNhNDIyYS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMDlGOTcxOUZGMTU5MjIyRTFENkRFRUY5QTQ5OTJDQTQ4RkEwOTg0RTM4NTg4RUJFOEE4NzkwNEUzRjQwNzdGMA==" Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=trace msg="received accesspoint pong ack" Jun 07 20:55:32 rivo.rick volumio[3128]: info: Shairport-Sync Started Jun 07 20:55:32 rivo.rick volumio[3128]: Error adding Membership: Error: addMembership EINVAL Jun 07 20:55:32 rivo.rick volumio[3128]: info: Shairport-Sync Started Jun 07 20:55:32 rivo.rick volumio[3128]: info: Shairport-Sync Started Jun 07 20:55:32 rivo.rick volumio[3128]: info: Volumio BT Module successfully started Jun 07 20:55:32 rivo.rick volumio[3128]: info: Turning Off Onboard LEDs Jun 07 20:55:32 rivo.rick sudo[3997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jun 07 20:55:32 rivo.rick sudo[3997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick sudo[3997]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 07 20:55:32 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:32+02:00" level=debug msg="update volume to 65535/65535" Jun 07 20:55:32 rivo.rick sudo[4000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jun 07 20:55:32 rivo.rick sudo[4000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:32 rivo.rick sudo[4000]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:32 rivo.rick volumio[3128]: info: TidalConnect service stoped! Jun 07 20:55:32 rivo.rick volumio[3128]: info: Successfully retrieved User Session From TIDAL Jun 07 20:55:32 rivo.rick volumio[3128]: error: updateQueue error: null Jun 07 20:55:32 rivo.rick volumio[3128]: info: Onboard LEDs initialized Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::servicePushState Jun 07 20:55:32 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:32 rivo.rick volumio[3128]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received inputs Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jun 07 20:55:32 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:33 rivo.rick volumio[3928]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 07 20:55:33 rivo.rick volumio[3128]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 20:55:33 rivo.rick volumio[3128]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 20:55:33 rivo.rick volumio[3128]: SPOTIFY: User informations: {"country":"ES","display_name":"montana20052001","email":"montana20052001@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/montana20052001"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/montana20052001","id":"montana20052001","images":[],"product":"free","type":"user","uri":"spotify:user:montana20052001"} Jun 07 20:55:33 rivo.rick volumio[3128]: info: Spotify Successfully logged in Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 20:55:33 rivo.rick volumio[3128]: info: [1749322533119] CoreMusicLibrary::Adding element Spotify Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:33 rivo.rick volumio[3128]: Cannot find translation for source Spotify Jun 07 20:55:33 rivo.rick sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 20:55:33 rivo.rick sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:33 rivo.rick systemd[1]: Started Volumio Tidal Connect Service. Jun 07 20:55:33 rivo.rick sudo[4025]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:33 rivo.rick volumio[3128]: info: Successfully retrieved User Subscription From TIDAL Jun 07 20:55:33 rivo.rick volumio[3128]: info: Adding TIDAL to Browse Sources Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 20:55:33 rivo.rick volumio[3128]: info: [1749322533266] CoreMusicLibrary::Adding element TIDAL Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:33 rivo.rick volumio[3128]: Cannot find translation for source Spotify Jun 07 20:55:33 rivo.rick volumio[3128]: Cannot find translation for source TIDAL Jun 07 20:55:33 rivo.rick volumio[3128]: info: Stopping AccessToken refresher cron Jun 07 20:55:33 rivo.rick sudo[4031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 07 20:55:33 rivo.rick sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:33 rivo.rick volumiobt[3976]: pulseaudio: no process found Jun 07 20:55:33 rivo.rick volumio[3128]: info: AccessToken refresher cron started Jun 07 20:55:33 rivo.rick volumio[3128]: info: Adding TIDAL REST API Endpoints Jun 07 20:55:33 rivo.rick sudo[4031]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:33 rivo.rick volumio[3128]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 07 20:55:33 rivo.rick dbus-daemon[2849]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=4035 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jun 07 20:55:33 rivo.rick systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 07 20:55:33 rivo.rick volumio[3128]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 07 20:55:33 rivo.rick kernel: IPVS: Creating netns size=1928 id=3 Jun 07 20:55:33 rivo.rick dbus-daemon[2849]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 07 20:55:33 rivo.rick systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Successfully called chroot. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Successfully dropped privileges. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Successfully limited resources. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Running. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Canary thread running. Jun 07 20:55:33 rivo.rick rtkit-daemon[4036]: Watchdog thread running. Jun 07 20:55:33 rivo.rick dbus-daemon[2849]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=4036 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jun 07 20:55:33 rivo.rick systemd[1]: Starting Authorization Manager... Jun 07 20:55:33 rivo.rick volumio[3128]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 07 20:55:33 rivo.rick polkitd[4039]: started daemon version 0.105 using authority implementation `local' version `0.105' Jun 07 20:55:33 rivo.rick dbus-daemon[2849]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jun 07 20:55:33 rivo.rick systemd[1]: Started Authorization Manager. Jun 07 20:55:33 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 07 20:55:33 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:33+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 07 20:55:33 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:33+02:00" level=trace msg="emitting websocket event: volume" Jun 07 20:55:33 rivo.rick volumio[3128]: info: Executing endpoint tc_getconfig Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 07 20:55:33 rivo.rick vtcs[4027]: STARTING TidalConnect services, version: 1.4.0.34 Jun 07 20:55:33 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:33 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:33 rivo.rick volumio[3128]: info: MCU Signalled Playback Inactive Jun 07 20:55:34 rivo.rick vtcs[4027]: STARTED TidalConnect services. Jun 07 20:55:34 rivo.rick volumio[3128]: info: Executing endpoint tc_connect Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 07 20:55:34 rivo.rick volumio[3128]: info: Connecting to TidalConnect Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::servicePushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::servicePushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreStateMachine::pushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioPushState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:34 rivo.rick volumio[3128]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 07 20:55:34 rivo.rick pulseaudio[4035]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 07 20:55:34 rivo.rick pulseaudio[4035]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 07 20:55:34 rivo.rick bluetoothd[3314]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Jun 07 20:55:34 rivo.rick bluetoothd[3314]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Jun 07 20:55:34 rivo.rick volumiobt[3976]: Applying permissions Jun 07 20:55:34 rivo.rick volumiobt[3976]: Setting BT discoverable and pairable Jun 07 20:55:34 rivo.rick pulseaudio[4035]: 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 Jun 07 20:55:34 rivo.rick volumiobt[3976]: Agent registered Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# -e power on Jun 07 20:55:34 rivo.rick volumiobt[3976]: Invalid command in menu main: -e Jun 07 20:55:34 rivo.rick volumiobt[3976]: Jun 07 20:55:34 rivo.rick volumiobt[3976]: Use "help" for a list of available commands in a menu. Jun 07 20:55:34 rivo.rick volumiobt[3976]: Use "menu " if you want to enter any submenu. Jun 07 20:55:34 rivo.rick volumiobt[3976]: Use "back" if you want to return to menu main. Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# agent on Jun 07 20:55:34 rivo.rick volumiobt[3976]: Agent is already registered Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# discoverable on Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# pairable on Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# agent NoInputNoOutput Jun 07 20:55:34 rivo.rick volumiobt[3976]: Agent is already registered Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# default-agent Jun 07 20:55:34 rivo.rick volumiobt[3976]: [bluetooth]# quit Jun 07 20:55:34 rivo.rick volumiobt[3976]: [59B blob data] Jun 07 20:55:34 rivo.rick volumio[3128]: 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: 4 Jun 07 20:55:34 rivo.rick volumio[3128]: info: go-librespot daemon successfully initialized Jun 07 20:55:36 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 20:55:36 rivo.rick volumio[3128]: info: TidalConnect service started! Jun 07 20:55:36 rivo.rick volumio[3128]: info: Completed starting Core Plugins Jun 07 20:55:36 rivo.rick volumio[3128]: info: ------------------------------------------- Jun 07 20:55:36 rivo.rick volumio[3128]: info: ----- MyVolumio plugins startup ---- Jun 07 20:55:36 rivo.rick volumio[3128]: info: ------------------------------------------- Jun 07 20:55:36 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 07 20:55:36 rivo.rick volumio[3128]: info: Reporting MCU Network Status: 1 Jun 07 20:55:36 rivo.rick volumio[3128]: info: Volumio Network Manager: Network status updated: 1 Jun 07 20:55:36 rivo.rick volumio[3928]: .........................................++++ Jun 07 20:55:37 rivo.rick mpd[3854]: exception: No database Jun 07 20:55:37 rivo.rick mpd[3854]: exception: No database Jun 07 20:55:37 rivo.rick mpd[3854]: exception: No database Jun 07 20:55:37 rivo.rick volumio[3128]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jun 07 20:55:37 rivo.rick volumio[3928]: .......++++ Jun 07 20:55:37 rivo.rick volumio[3928]: e is 65537 (0x010001) Jun 07 20:55:37 rivo.rick volumio[3928]: writing RSA key Jun 07 20:55:37 rivo.rick volumio[3128]: 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 Jun 07 20:55:37 rivo.rick volumio[3128]: info: Initializing connection to go-librespot Websocket Jun 07 20:55:37 rivo.rick go-librespot[3933]: time="2025-06-07T20:55:37+02:00" level=debug msg="new websocket client" Jun 07 20:55:37 rivo.rick volumio[3128]: info: Connection to go-librespot Websocket established Jun 07 20:55:39 rivo.rick systemd[1]: systemd-hostnamed.service: Succeeded. Jun 07 20:55:40 rivo.rick volumio[3128]: info: Checking for updated MCU Firmware Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:55:40 rivo.rick volumio[3128]: info: Firware on device is on latest version, no need to update Jun 07 20:55:40 rivo.rick volumio[3128]: 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: 6 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 20:55:40 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:40 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: Listing playlists Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 20:55:40 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:40 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 20:55:40 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:40 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: info: Listing playlists Jun 07 20:55:40 rivo.rick volumio[3128]: info: Getting Spotify volume Jun 07 20:55:40 rivo.rick volumio[3128]: info: Spotify volume: 100 Jun 07 20:55:40 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 07 20:55:40 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:40 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:40 rivo.rick volumio[3128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 07 20:55:41 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 20:55:42 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:42 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:42 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:42 rivo.rick kernel: fb: mem_free_work, free memory: addr:800000 Jun 07 20:55:42 rivo.rick volumio[3128]: info: Executing endpoint metavolumio Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 07 20:55:42 rivo.rick volumio[3128]: info: Executing endpoint metavolumio Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 07 20:55:42 rivo.rick volumio[3128]: info: Executing endpoint metavolumio Jun 07 20:55:42 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 07 20:55:42 rivo.rick systemd[1]: systemd-timedated.service: Succeeded. Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 07 20:55:44 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 07 20:55:46 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 07 20:55:46 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 07 20:55:46 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:46 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:46 rivo.rick volumio[3128]: info: Starting MyVolumio Remote Streaming Endpoints Jun 07 20:55:46 rivo.rick volumio[3128]: info: MyVolumio login type: Token Jun 07 20:55:46 rivo.rick volumio[3128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 07 20:55:46 rivo.rick volumio[3128]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jun 07 20:55:46 rivo.rick volumio[3128]: error: MyVolumio Custom Token format not valid, refreshing it Jun 07 20:55:47 rivo.rick volumio[3128]: info: MyVolumio login type: Token Jun 07 20:55:47 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 07 20:55:47 rivo.rick volumio[3128]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 07 20:55:49 rivo.rick volumio[3128]: info: MyVolumio token set successfully Jun 07 20:55:49 rivo.rick volumio[3128]: info: MYVOLUMIO: Adding device Jun 07 20:55:49 rivo.rick volumio[3128]: info: MYVOLUMIO: Evaluating Server Jun 07 20:55:50 rivo.rick volumio[3128]: info: MyVolumio status changed Jun 07 20:55:50 rivo.rick volumio[3128]: info: Streaming services startup Jun 07 20:55:50 rivo.rick volumio[3128]: info: Starting Streaming Daemon Jun 07 20:55:50 rivo.rick volumio[3128]: info: Removing browser output: myVolumio user plan is not superstar Jun 07 20:55:50 rivo.rick volumio[3128]: info: Removing audio output: Jun 07 20:55:50 rivo.rick volumio[3128]: info: Stoppping Tunnel 1 Jun 07 20:55:50 rivo.rick sudo[4164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 20:55:50 rivo.rick sudo[4164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:50 rivo.rick sudo[4164]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:50 rivo.rick sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 07 20:55:50 rivo.rick sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:55:50 rivo.rick volumio[3128]: error: Cannot start Volumio Streaming Daemon Jun 07 20:55:50 rivo.rick volumio[3128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 20:55:50 rivo.rick volumio[3128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 20:55:50 rivo.rick sudo[4167]: pam_unix(sudo:session): session closed for user root Jun 07 20:55:50 rivo.rick volumio[3128]: info: Remote SSH Stopped Jun 07 20:55:50 rivo.rick volumio[3128]: info: Setting Geolocation for MyVolumio to eu3 Jun 07 20:55:50 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:50 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:50 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:50 rivo.rick volumio[3128]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 07 20:55:51 rivo.rick volumio[3128]: info: Updating MyVolumio device info Jun 07 20:55:51 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:51 rivo.rick volumio[3128]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 07 20:55:54 rivo.rick volumio[3128]: info: MYVOLUMIO: Adding device Jun 07 20:55:54 rivo.rick volumio[3128]: info: MYVOLUMIO: Evaluating Server Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 20:55:55 rivo.rick volumio[3128]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 07 20:55:55 rivo.rick volumio[3128]: info: Received Get System Version Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:55:55 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:55 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:55 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:55 rivo.rick volumio[3128]: info: Setting Geolocation for MyVolumio to eu3 Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:55 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:56 rivo.rick volumio[3128]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 07 20:55:56 rivo.rick volumio[3128]: error: MyVolumio Plugin failed to start in a timely fashion Jun 07 20:55:56 rivo.rick volumio[3128]: [Metrics] CommandRouter: 53s 339.41ms Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::volumiosetStartupVolume Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::Close All Modals sent Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::Close All Modals sent Jun 07 20:55:56 rivo.rick volumio[3128]: info: Updating MyVolumio device info Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:56 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:55:57 rivo.rick volumio[3128]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 07 20:55:57 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 07 20:55:57 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 20:55:57 rivo.rick volumio[3128]: No protocol specified Jun 07 20:55:57 rivo.rick volumio[3128]: xcb_connection_has_error() returned true Jun 07 20:55:57 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:55:59 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:59 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:55:59 rivo.rick volumio[3128]: verbose: New Socket.io Connection to 192.168.0.36:3000 from 192.168.0.18 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:55:59 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 07 20:55:59 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 07 20:56:00 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:56:00 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 20:56:00 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 20:56:00 rivo.rick volumio-remote-updater[2841]: No test mode Jun 07 20:56:00 rivo.rick volumio-remote-updater[2841]: No alpha test mode Jun 07 20:56:00 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 07 20:56:00 rivo.rick volumio[3128]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 07 20:56:00 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:01 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:01 rivo.rick volumio[3128]: verbose: New Socket.io Connection to 192.168.0.36:3000 from 192.168.0.18 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:01 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:01 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 20:56:01 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 07 20:56:01 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:01 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:01 rivo.rick sudo[4254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 20:56:01 rivo.rick sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:01 rivo.rick sudo[4254]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:01 rivo.rick sudo[4256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 20:56:01 rivo.rick sudo[4256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:01 rivo.rick sudo[4256]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:02 rivo.rick volumio[3128]: verbose: New Socket.io Connection to 192.168.0.36 from 192.168.0.18 UA: Mozilla/5.0 (Linux; Android 15; 23113RKC6G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 07 20:56:02 rivo.rick sudo[4262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 20:56:02 rivo.rick sudo[4262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:02 rivo.rick sudo[4264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 20:56:02 rivo.rick sudo[4264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:02 rivo.rick sudo[4262]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:02 rivo.rick sudo[4264]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:02 rivo.rick volumio[3128]: verbose: New Socket.io Connection to 192.168.0.36 from 192.168.0.18 UA: Mozilla/5.0 (Linux; Android 15; 23113RKC6G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:02 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetQueue Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreStateMachine::getQueue Jun 07 20:56:02 rivo.rick volumio[3128]: info: CorePlayQueue::getQueue Jun 07 20:56:02 rivo.rick volumio[3128]: info: Listing playlists Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 20:56:02 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:56:02 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:02 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:02 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 20:56:02 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 07 20:56:02 rivo.rick go-librespot[3933]: time="2025-06-07T20:56:02+02:00" level=trace msg="sent dealer ping" Jun 07 20:56:02 rivo.rick go-librespot[3933]: time="2025-06-07T20:56:02+02:00" level=trace msg="received dealer pong" Jun 07 20:56:03 rivo.rick volumio[3128]: info: BOOT COMPLETED Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 20:56:04 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:56:04 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:04 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:56:04 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:04 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 20:56:04 rivo.rick volumio[3128]: info: Not Reporting Auto name since its the default one Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 07 20:56:04 rivo.rick volumio[3128]: No protocol specified Jun 07 20:56:04 rivo.rick volumio[3128]: xcb_connection_has_error() returned true Jun 07 20:56:04 rivo.rick sudo[4280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 07 20:56:04 rivo.rick sudo[4280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:04 rivo.rick sudo[4280]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:04 rivo.rick sudo[4282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 07 20:56:04 rivo.rick sudo[4282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 20:56:04 rivo.rick systemd[1]: Started RAAT DAEMON. Jun 07 20:56:04 rivo.rick sudo[4282]: pam_unix(sudo:session): session closed for user root Jun 07 20:56:04 rivo.rick volumio[3128]: info: Raat Daemon started successfully Jun 07 20:56:04 rivo.rick volumio[3128]: info: Executing endpoint restartRAATSocket Jun 07 20:56:04 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 07 20:56:04 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:04 rivo.rick volumio[3128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 07 20:56:05 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 20:56:05 rivo.rick volumio[3128]: info: Received Get System Info Jun 07 20:56:05 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 20:56:05 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 20:56:05 rivo.rick volumio[3128]: info: Discovery: Getting this device information Jun 07 20:56:05 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetState Jun 07 20:56:05 rivo.rick volumio[3128]: info: CorePlayQueue::getTrack 0 Jun 07 20:56:05 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 20:56:06 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 20:56:07 rivo.rick volumio[3128]: info: RAAT: Requesting Headphone Status Jun 07 20:56:07 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 07 20:56:07 rivo.rick volumio[3128]: info: MCU Signalled Headphone Mode Disabled Jun 07 20:56:07 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 07 20:56:07 rivo.rick volumio[3128]: info: Reporting Headphone State: false Jun 07 20:56:07 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:07 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 20:56:07 rivo.rick volumio[3128]: info: Updating RAAT Signal Path Jun 07 20:56:09 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:09 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:10 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:10 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:11 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:11 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:12 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 07 20:56:12 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:12 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:13 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:13 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:14 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:14 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:14 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:15 rivo.rick volumio[3128]: info: Preload queue cleared Jun 07 20:56:15 rivo.rick volumio[3128]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 20:56:15 rivo.rick volumio[3128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 20:56:15 rivo.rick volumio[3128]: TypeError: Cannot read property 'length' of undefined Jun 07 20:56:15 rivo.rick volumio[3128]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 07 20:56:15 rivo.rick volumio[3128]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 07 20:56:15 rivo.rick volumio[3128]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 07 20:56:15 rivo.rick volumio[3128]: at Parser.emit (events.js:400:28) Jun 07 20:56:15 rivo.rick volumio[3128]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 07 20:56:15 rivo.rick volumio[3128]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 07 20:56:15 rivo.rick volumio[3128]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 07 20:56:15 rivo.rick volumio[3128]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 07 20:56:15 rivo.rick volumio[3128]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 07 20:56:15 rivo.rick volumio[3128]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 07 20:56:15 rivo.rick volumio[3128]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 07 20:56:15 rivo.rick volumio[3128]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 07 20:56:15 rivo.rick volumio[3128]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 07 20:56:15 rivo.rick volumio[3128]: at IncomingMessage.emit (events.js:412:35) Jun 07 20:56:15 rivo.rick volumio[3128]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 07 20:56:15 rivo.rick volumio[3128]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 07 20:56:15 rivo.rick volumio[3128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 20:56:16 rivo.rick sudo[4335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 20:55 Jun 07 20:56:16 rivo.rick sudo[4335]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"