-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2024-11-20 18:49:10 CET. -- Nov 20 18:48:49 rivo systemd-timedated[3311]: Changed local time to Wed Nov 20 18:48:49 2024 Nov 20 18:48:49 rivo sudo[3309]: pam_unix(sudo:session): session closed for user root Nov 20 18:48:49 rivo volumio-time-update[2816]: volumio-time-update-util: System time updated successfully. Nov 20 18:48:49 rivo systemd[1]: Started Volumio Time Update Utility. Nov 20 18:48:49 rivo systemd[1]: Starting Daily apt download activities... Nov 20 18:48:49 rivo systemd[1]: Starting Daily man-db regeneration... Nov 20 18:48:49 rivo ntpd[3056]: Listen normally on 3 eth0 192.168.0.101:123 Nov 20 18:48:49 rivo ntpd[3056]: new interface(s) found: waking up resolver Nov 20 18:48:49 rivo volumio[3087]: info: Plugin bluetooth is not enabled Nov 20 18:48:49 rivo volumio[3087]: info: Loading plugin "alarm-clock"... Nov 20 18:48:49 rivo systemd[1]: man-db.service: Succeeded. Nov 20 18:48:49 rivo systemd[1]: Started Daily man-db regeneration. Nov 20 18:48:49 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Nov 20 18:48:49 rivo systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 20 18:48:49 rivo volumio[3087]: info: Loading plugin "manifestui"... Nov 20 18:48:49 rivo systemd[1]: Started Load/Save RF Kill Switch Status. Nov 20 18:48:49 rivo systemd[1]: Starting Bluetooth service... Nov 20 18:48:49 rivo bluetoothd[3337]: Bluetooth daemon 5.50 Nov 20 18:48:49 rivo bluetoothd[3337]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 20 18:48:49 rivo systemd[1]: Started Bluetooth service. Nov 20 18:48:49 rivo bluetoothd[3337]: Starting SDP server Nov 20 18:48:49 rivo bluetoothd[3337]: Excluding (cli) sap Nov 20 18:48:49 rivo systemd[1]: Reached target Bluetooth. Nov 20 18:48:49 rivo bluetoothd[3337]: Bluetooth management interface 1.14 initialized Nov 20 18:48:49 rivo dbus-daemon[2819]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=3337 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Nov 20 18:48:49 rivo systemd[1]: Starting Hostname Service... Nov 20 18:48:49 rivo kernel: IPVS: Creating netns size=1928 id=2 Nov 20 18:48:49 rivo volumio[3087]: info: Loading plugin "metavolumio"... Nov 20 18:48:50 rivo dbus-daemon[2819]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 20 18:48:50 rivo systemd[1]: Started Hostname Service. Nov 20 18:48:50 rivo systemd[1]: apt-daily.service: Succeeded. Nov 20 18:48:50 rivo systemd[1]: Started Daily apt download activities. Nov 20 18:48:50 rivo systemd[1]: Starting Daily apt upgrade and clean activities... Nov 20 18:48:50 rivo nmbd[3050]: [2024/11/20 18:48:50.715180, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 18:48:50 rivo systemd[1]: Started Samba NMB Daemon. Nov 20 18:48:50 rivo nmbd[3050]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 20 18:48:50 rivo systemd[1]: Starting Samba Winbind Daemon... Nov 20 18:48:51 rivo winbindd[3402]: [2024/11/20 18:48:51.122996, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 20 18:48:51 rivo winbindd[3402]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 20 18:48:51 rivo winbindd[3402]: [2024/11/20 18:48:51.138948, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 18:48:51 rivo systemd[1]: Started Samba Winbind Daemon. Nov 20 18:48:51 rivo winbindd[3402]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 20 18:48:51 rivo systemd[1]: Starting Samba SMB Daemon... Nov 20 18:48:51 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 20 18:48:51 rivo systemd[1]: Started Daily apt upgrade and clean activities. Nov 20 18:48:51 rivo volumio[3087]: info: Loading plugin "airplay_emulation"... Nov 20 18:48:51 rivo volumio[3087]: info: Starting Shairport Sync Nov 20 18:48:51 rivo volumio[3087]: info: Loading plugin "cd_controller"... Nov 20 18:48:51 rivo smbd[3433]: [2024/11/20 18:48:51.831341, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 18:48:51 rivo systemd[1]: Started Samba SMB Daemon. Nov 20 18:48:51 rivo smbd[3433]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 20 18:48:51 rivo systemd[1]: Reached target Multi-User System. Nov 20 18:48:51 rivo systemd[1]: Reached target Graphical Interface. Nov 20 18:48:51 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 20 18:48:51 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 20 18:48:51 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 20 18:48:51 rivo systemd[1]: Startup finished in 12.861s (kernel) + 17.705s (userspace) = 30.566s. Nov 20 18:48:52 rivo volumio[3087]: info: Loading plugin "last_100"... Nov 20 18:48:52 rivo volumio[3087]: info: Plugin raat is not enabled Nov 20 18:48:52 rivo volumio[3087]: info: Loading plugin "streaming_services"... Nov 20 18:48:54 rivo volumio[3087]: info: Starting Streaming Service Transparent Proxy Nov 20 18:48:54 rivo volumio[3087]: info: Loading plugin "tidalconnect"... Nov 20 18:48:54 rivo systemd[1]: systemd-rfkill.service: Succeeded. Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "webradio"... Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "i2s_dacs"... Nov 20 18:48:55 rivo volumio[3087]: info: I2S DAC not set, start Auto-detection Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "volumiodiscovery"... Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 18:48:55 rivo node[3087]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 20 18:48:55 rivo node[3087]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** For more information see Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 18:48:55 rivo volumio[3087]: *** WARNING *** For more information see Nov 20 18:48:55 rivo node[3087]: *** WARNING *** For more information see Nov 20 18:48:55 rivo node[3087]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 20 18:48:55 rivo node[3087]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 18:48:55 rivo node[3087]: *** WARNING *** For more information see Nov 20 18:48:55 rivo volumio[3087]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 20 18:48:55 rivo volumio[3087]: info: Discovery: Started advertising with name: Rivo Nov 20 18:48:55 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 20 18:48:55 rivo volumio[3087]: info: Plugin multiroom is not enabled Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "outputs"... Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "albumart"... Nov 20 18:48:55 rivo volumio[3087]: info: Plugin example_plugin is not enabled Nov 20 18:48:55 rivo volumio[3087]: info: Loading plugin "hi_res_audio"... Nov 20 18:48:56 rivo volumio[3087]: Forking 3 albumart workers Nov 20 18:48:56 rivo volumio[3087]: STREAMING PROXY: Starting server on port 3245 Nov 20 18:48:56 rivo volumio[3087]: Node JS runtime: 14 Nov 20 18:48:57 rivo volumio-remote-updater[2818]: [2024-11-20 18:48:57] [connect] Successful connection Nov 20 18:48:58 rivo volumio[3087]: Starting albumart workers Nov 20 18:48:58 rivo volumio[3087]: Starting albumart workers Nov 20 18:48:58 rivo volumio[3087]: Starting albumart workers Nov 20 18:48:59 rivo volumio[3087]: info: Applying required configuration parameters for plugin hi_res_audio Nov 20 18:48:59 rivo volumio[3087]: info: Loading plugin "inputs"... Nov 20 18:49:00 rivo volumio[3087]: info: Loading plugin "qobuz"... Nov 20 18:49:02 rivo volumio[3087]: info: Loading plugin "smart_inputs"... Nov 20 18:49:02 rivo volumio[3087]: info: Loading plugin "tidal"... Nov 20 18:49:04 rivo volumio[3087]: info: Loading plugin "rivocontrol"... Nov 20 18:49:04 rivo volumio[3087]: info: Adding this device properties Nov 20 18:49:04 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Nov 20 18:49:04 rivo volumio[3087]: info: Setting Additional Device Volumio Properties: [object Object] Nov 20 18:49:04 rivo volumio[3087]: info: Loading plugin "updater_comm"... Nov 20 18:49:05 rivo volumio[3087]: info: Loading plugin "cec_controller"... Nov 20 18:49:05 rivo volumio[3087]: info: Applying required configuration parameters for plugin cec_controller Nov 20 18:49:05 rivo volumio[3087]: info: Plugin mpdemulation is not enabled Nov 20 18:49:05 rivo volumio[3087]: info: Loading plugin "rest_api"... Nov 20 18:49:05 rivo volumio[3087]: info: Loading plugin "websocket"... Nov 20 18:49:05 rivo volumio[3087]: info: Starting Socket.io Server version 2.3.0 Nov 20 18:49:05 rivo volumio[3087]: info: Loading plugin "Systeminfo"... Nov 20 18:49:06 rivo volumio[3087]: info: Loading i18n strings for locale de Nov 20 18:49:06 rivo volumio[3087]: Updating browse sources language Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::initPlayerControls Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: Express server listening on port 3000 Nov 20 18:49:06 rivo volumio[3087]: [Metrics] WebUI: 23s 567.86ms Nov 20 18:49:06 rivo volumio[3087]: info: CoreStateMachine::resetVolumioState Nov 20 18:49:06 rivo volumio[3087]: info: CoreStateMachine::getcurrentVolume Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 20 18:49:06 rivo volumio[3087]: info: CoreStateMachine::getcurrentVolume Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 18:49:06 rivo sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 20 18:49:06 rivo sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:06 rivo sudo[3669]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:06 rivo sudo[3671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 20 18:49:06 rivo sudo[3671]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:06 rivo volumio[3087]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 20 18:49:06 rivo volumio[3087]: info: Completed loading Core Plugins Nov 20 18:49:06 rivo volumio[3087]: info: Preparing to generate the ALSA configuration file Nov 20 18:49:06 rivo sudo[3671]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:06 rivo volumio[3087]: info: Volumio Network Manager: Network status updated: 1 Nov 20 18:49:06 rivo volumio[3087]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 20 18:49:06 rivo volumio[3087]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 20 18:49:06 rivo volumio[3087]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 20 18:49:06 rivo volumio[3087]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 1 Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 3 Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 4 Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 5 Nov 20 18:49:06 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 20 18:49:06 rivo volumio[3087]: info: VolumeController:: Volume=undefined Mute =false Nov 20 18:49:06 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:06 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:06 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:07 rivo volumio[3087]: info: CoreStateMachine::updateTrackBlock Nov 20 18:49:07 rivo volumio[3087]: info: CorePlayQueue::getTrackBlock Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 18:49:07 rivo volumio[3087]: info: VolumeController:: Volume=undefined Mute =false Nov 20 18:49:07 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:07 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:07 rivo volumio[3087]: info: CoreStateMachine::updateTrackBlock Nov 20 18:49:07 rivo volumio[3087]: info: CorePlayQueue::getTrackBlock Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 18:49:07 rivo volumio[3087]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 20 18:49:07 rivo volumio[3087]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 20 18:49:07 rivo volumio[3087]: info: Reloading queue from file Nov 20 18:49:07 rivo volumio[3087]: info: VolumeController:: Volume=undefined Mute =false Nov 20 18:49:07 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:07 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:07 rivo volumio[3087]: info: Asound.conf file unchanged, so no further update is needed Nov 20 18:49:07 rivo volumio[3087]: info: Output device has changed, restarting MPD Nov 20 18:49:07 rivo sudo[3688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 20 18:49:07 rivo sudo[3688]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo volumio[3087]: info: Output device has changed, restarting Shairport Sync Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 18:49:07 rivo sudo[3688]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:07 rivo sudo[3691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 20 18:49:07 rivo sudo[3691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo systemd[1]: Stopping Music Player Daemon... Nov 20 18:49:07 rivo volumio[3087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 18:49:07 rivo volumio[3087]: info: ___________ START PLUGINS ___________ Nov 20 18:49:07 rivo volumio[3087]: info: ControllerMpd::onStart: Initializing MPD Nov 20 18:49:07 rivo volumio[3087]: info: Creating MPD Configuration file Nov 20 18:49:07 rivo systemd[1]: systemd-fsckd.service: Succeeded. Nov 20 18:49:07 rivo systemd[1]: mpd.service: Succeeded. Nov 20 18:49:07 rivo systemd[1]: Stopped Music Player Daemon. Nov 20 18:49:07 rivo systemd[1]: Starting Music Player Daemon... Nov 20 18:49:07 rivo sudo[3697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 20 18:49:07 rivo sudo[3697]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 18:49:07 rivo volumio[3087]: info: [1732124947665] CoreMusicLibrary::Adding element Medienserver Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:07 rivo volumio[3087]: info: Adding METAVOLUMIO REST API Endpoints Nov 20 18:49:07 rivo sudo[3697]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:07 rivo volumio[3087]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 20 18:49:07 rivo volumio[3087]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 20 18:49:07 rivo volumio[3087]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 20 18:49:07 rivo volumio[3087]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 20 18:49:07 rivo sudo[3707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 18:49:07 rivo sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 20 18:49:07 rivo systemd[1]: mpd.service: Succeeded. Nov 20 18:49:07 rivo systemd[1]: Stopped Music Player Daemon. Nov 20 18:49:07 rivo systemd[1]: Starting Music Player Daemon... Nov 20 18:49:07 rivo volumio[3087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 18:49:07 rivo volumio[3087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 18:49:07 rivo volumio[3087]: info: Preparing CD Folders Nov 20 18:49:07 rivo volumio[3087]: info: Adding CD REST API Endpoints Nov 20 18:49:07 rivo volumio[3087]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 20 18:49:07 rivo volumio[3087]: info: Starting UDEV Watcher for CD Nov 20 18:49:07 rivo volumio[3087]: info: Detecting CD presence with UDEV Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 18:49:07 rivo volumio[3087]: info: [1732124947898] CoreMusicLibrary::Adding element Last_100 Nov 20 18:49:07 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:07 rivo sudo[3712]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 20 18:49:07 rivo sudo[3712]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo volumio[3087]: info: Streaming services startup Nov 20 18:49:07 rivo volumio[3087]: info: Starting Streaming Daemon Nov 20 18:49:07 rivo sudo[3712]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:07 rivo sudo[3736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 20 18:49:07 rivo sudo[3736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:07 rivo sudo[3736]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 18:49:08 rivo volumio[3087]: info: [1732124948020] CoreMusicLibrary::Adding element Webradio Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 18:49:08 rivo volumio[3087]: info: Initializing BBC Radios Nov 20 18:49:08 rivo sudo[3745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 20 18:49:08 rivo sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3745]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 18:49:08 rivo volumio[3087]: error: Hi Res Audio Failed Login: Missing Login Data Nov 20 18:49:08 rivo volumio[3087]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 20 18:49:08 rivo volumio[3087]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 20 18:49:08 rivo volumio[3087]: info: Initializing Serial Communication on port /dev/ttyS3 Nov 20 18:49:08 rivo volumio[3087]: info: Touch Event Listener Process Starting Nov 20 18:49:08 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Nov 20 18:49:08 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Nov 20 18:49:08 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Nov 20 18:49:08 rivo volumio[3087]: info: Refreshing QOBUZ token Nov 20 18:49:08 rivo sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Nov 20 18:49:08 rivo sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo volumio[3087]: info: Adding inputs REST Endpoints Nov 20 18:49:08 rivo volumio[3087]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 20 18:49:08 rivo volumio[3087]: info: Scanning Audio Inputs Nov 20 18:49:08 rivo volumio[3087]: info: Checking against Known Cards name Nov 20 18:49:08 rivo volumio[3087]: info: Checking against Known Cards name Nov 20 18:49:08 rivo volumio[3087]: info: Checking against Known Cards name Nov 20 18:49:08 rivo volumio[3087]: info: Checking against Known Cards name Nov 20 18:49:08 rivo volumio[3087]: info: Checking against Known Cards name Nov 20 18:49:08 rivo volumio[3087]: info: Adding Server instance for streaming Nov 20 18:49:08 rivo sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Nov 20 18:49:08 rivo sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3770]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo sudo[3773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Nov 20 18:49:08 rivo sudo[3773]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3773]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo sudo[3776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Nov 20 18:49:08 rivo sudo[3776]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3776]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo sudo[3779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Nov 20 18:49:08 rivo sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3779]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo volumio[3087]: info: Apply VIM3L Onboard LEDs Settings Nov 20 18:49:08 rivo volumio[3087]: info: Turning On Onboard LEDs Nov 20 18:49:08 rivo sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Nov 20 18:49:08 rivo sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo volumio[3087]: info: Starting CEC Event listener Nov 20 18:49:08 rivo sudo[3783]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo volumio[3087]: info: Volumio Calling Home Nov 20 18:49:08 rivo sudo[3787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Nov 20 18:49:08 rivo sudo[3787]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:08 rivo sudo[3787]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:08 rivo volumio[3087]: info: Stopping AccessToken refresher cron Nov 20 18:49:08 rivo volumio[3087]: info: AccessToken refresher cron started Nov 20 18:49:08 rivo volumio[3087]: info: Adding TIDAL REST API Endpoints Nov 20 18:49:08 rivo volumio[3087]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 20 18:49:08 rivo volumio[3087]: info: CoreStateMachine::setRepeat null single undefined Nov 20 18:49:08 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:08 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:08 rivo volumio[3087]: info: CoreStateMachine::setRandom null Nov 20 18:49:08 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:08 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:08 rivo volumio[3087]: info: VolumeController:: Volume=undefined Mute =false Nov 20 18:49:08 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:08 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:08 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:08 rivo volumio[3087]: info: Serial port opened successfully Nov 20 18:49:08 rivo volumio[3087]: info: Sending serial start messages Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:09 rivo volumio[3087]: info: Reporting MCU Network Status: 1 Nov 20 18:49:09 rivo volumio[3087]: info: MP1 GPIO: Signalled system ready via GPIO Nov 20 18:49:09 rivo volumio[3087]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 7 Nov 20 18:49:09 rivo volumio[3087]: error: Cannot start Volumio Streaming Daemon Nov 20 18:49:09 rivo volumio[3087]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 20 18:49:09 rivo volumio[3087]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 20 18:49:09 rivo volumio[3087]: info: MPD Permissions set Nov 20 18:49:09 rivo volumio[3087]: info: MPD Permissions set Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Nov 20 18:49:09 rivo volumio[3087]: info: Setting Device Volume Override Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 20 18:49:09 rivo volumio[3087]: info: Updating Volume Controller Parameters: Device: 5 Name: Xonar Essence 3 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 20 18:49:09 rivo volumio[3087]: info: Disabling external Volume Control Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 18:49:09 rivo volumio[3087]: info: CoreStateMachine::pushState Nov 20 18:49:09 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioPushState Nov 20 18:49:09 rivo sudo[3797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 20 18:49:09 rivo sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:09 rivo volumio[3087]: info: Volumio called home Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Nov 20 18:49:09 rivo volumio[3087]: info: Setting Additional System Software info: Hardware Revision: 2.0 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Nov 20 18:49:09 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Nov 20 18:49:09 rivo volumio[3087]: info: Setting HW Firmware info: undefined Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Nov 20 18:49:09 rivo volumio[3087]: info: Setting HW Version info: 2.0 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Nov 20 18:49:09 rivo volumio[3087]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.4 Nov 20 18:49:09 rivo sudo[3797]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Nov 20 18:49:09 rivo volumio[3087]: info: Setting HW Firmware info: 0.3.4 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Nov 20 18:49:09 rivo volumio[3087]: info: Setting HW Version info: 2.0 Nov 20 18:49:09 rivo volumio[3087]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 20 18:49:09 rivo volumio[3087]: info: MCU Signalled Headphone Mode Disabled Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 20 18:49:09 rivo volumio[3087]: info: MCU Signalled Sleep Mode Disabled Nov 20 18:49:09 rivo volumio[3087]: info: Enabling Advanced system settings configuration Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Nov 20 18:49:09 rivo volumio[3087]: info: Additional UI Settings Added for plugin music_service/inputs Nov 20 18:49:09 rivo volumio[3087]: info: MCU Signalled Auto Boot Mode On Power Disabled Nov 20 18:49:09 rivo volumio[3087]: info: Discovery: adding 4caa69be-a434-4884-bf00-f369f09d799d Nov 20 18:49:09 rivo volumio[3087]: info: Discovery: Found device Rivo Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioGetState Nov 20 18:49:09 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:09 rivo sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Nov 20 18:49:09 rivo sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioGetVisibleSources Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioGetState Nov 20 18:49:09 rivo volumio[3087]: info: CorePlayQueue::getTrack 0 Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::volumioGetQueue Nov 20 18:49:09 rivo volumio[3087]: info: CoreStateMachine::getQueue Nov 20 18:49:09 rivo volumio[3087]: info: CorePlayQueue::getQueue Nov 20 18:49:09 rivo volumio[3087]: info: Listing playlists Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 20 18:49:09 rivo kernel: fb: osd[0] enable: 1 (Xorg) Nov 20 18:49:09 rivo sudo[3806]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 20 18:49:09 rivo volumio[3087]: Searching all installed plugins Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: , search Nov 20 18:49:09 rivo volumio[3087]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 20 18:49:09 rivo volumio[3087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:49:09 rivo volumio[3087]: TypeError: Cannot read property 'then' of undefined Nov 20 18:49:09 rivo volumio[3087]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Nov 20 18:49:09 rivo volumio[3087]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 20 18:49:09 rivo volumio[3087]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 20 18:49:09 rivo volumio[3087]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 20 18:49:09 rivo volumio[3087]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 20 18:49:09 rivo volumio[3087]: at Socket.emit (events.js:400:28) Nov 20 18:49:09 rivo volumio[3087]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 20 18:49:09 rivo volumio[3087]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 20 18:49:09 rivo volumio[3087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:49:10 rivo mpd[3738]: Nov 20 18:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 20 18:49:10 rivo systemd[1]: Started Music Player Daemon. Nov 20 18:49:10 rivo sudo[3691]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:10 rivo sudo[3707]: pam_unix(sudo:session): session closed for user root Nov 20 18:49:10 rivo sudo[3819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 18:48 Nov 20 18:49:10 rivo sudo[3819]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"