-- Logs begin at Sat 2024-08-17 21:03:45 WIB, end at Sat 2024-08-17 21:33:11 WIB. -- Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.963001c4 does not match aorg 0000000000.00000000 from server@116.12.47.30 xmt 0xea6b3317.e7e16978 Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.962d7f59 does not match aorg 0000000000.00000000 from server@103.17.182.30 xmt 0xea6b3317.e912a086 Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.9639430a does not match aorg 0000000000.00000000 from server@202.65.114.202 xmt 0xea6b3317.e84d1be7 Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.96237d64 does not match aorg 0000000000.00000000 from server@103.169.192.230 xmt 0xea6b3317.e6e07ae5 Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.963b74f6 does not match aorg 0000000000.00000000 from server@103.169.192.229 xmt 0xea6b3317.e723fe90 Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.9634774e does not match aorg 0000000000.00000000 from server@103.134.77.146 xmt 0xea6b3317.ec104d9a Aug 17 21:32:55 tube-rivo-audio ntpd[3419]: receive: Unexpected origin timestamp 0xea6b2c62.963238d3 does not match aorg 0000000000.00000000 from server@103.155.196.116 xmt 0xea6b3317.ea86b591 Aug 17 21:32:56 tube-rivo-audio volumio[3431]: info: Plugin airplay_emulation is not enabled Aug 17 21:32:56 tube-rivo-audio volumio[3431]: info: Plugin cd_controller is not enabled Aug 17 21:32:56 tube-rivo-audio volumio[3431]: info: Loading plugin "last_100"... Aug 17 21:32:56 tube-rivo-audio volumio[3431]: info: Plugin raat is not enabled Aug 17 21:32:56 tube-rivo-audio volumio[3431]: info: Loading plugin "streaming_services"... Aug 17 21:32:56 tube-rivo-audio kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e2dd000 drv_status 0x0 e_type 69 e_states 8 Aug 17 21:32:56 tube-rivo-audio kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e2dd000 drv_status 0x0 e_type 69 e_states 8 Aug 17 21:32:56 tube-rivo-audio kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e2dd000 drv_status 0x0 e_type 69 e_states 8 Aug 17 21:32:56 tube-rivo-audio kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e2dd000 drv_status 0x0 e_type 69 e_states 0 Aug 17 21:32:57 tube-rivo-audio sudo[3469]: pam_unix(sudo:session): session closed for user root Aug 17 21:32:57 tube-rivo-audio volumio[3431]: info: Starting Streaming Service Transparent Proxy Aug 17 21:32:57 tube-rivo-audio volumio[3431]: info: Loading plugin "tidalconnect"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "webradio"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "i2s_dacs"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: I2S DAC not set, start Auto-detection Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "volumiodiscovery"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** For more information see Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 21:32:58 tube-rivo-audio volumio[3431]: *** WARNING *** For more information see Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** For more information see Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 21:32:58 tube-rivo-audio node[3431]: *** WARNING *** For more information see Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Discovery: Started advertising with name: Tube Rivo Audio Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Plugin multiroom is not enabled Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "outputs"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "albumart"... Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Plugin example_plugin is not enabled Aug 17 21:32:58 tube-rivo-audio volumio[3431]: info: Loading plugin "hi_res_audio"... Aug 17 21:32:59 tube-rivo-audio volumio[3431]: Forking 3 albumart workers Aug 17 21:32:59 tube-rivo-audio volumio[3431]: STREAMING PROXY: Starting server on port 3245 Aug 17 21:32:59 tube-rivo-audio volumio[3431]: Node JS runtime: 14 Aug 17 21:33:00 tube-rivo-audio volumio[3431]: Starting albumart workers Aug 17 21:33:00 tube-rivo-audio volumio[3431]: Starting albumart workers Aug 17 21:33:00 tube-rivo-audio volumio[3431]: Starting albumart workers Aug 17 21:33:00 tube-rivo-audio volumio[3431]: info: Applying required configuration parameters for plugin hi_res_audio Aug 17 21:33:00 tube-rivo-audio volumio[3431]: info: Loading plugin "inputs"... Aug 17 21:33:01 tube-rivo-audio volumio[3431]: info: Loading plugin "qobuz"... Aug 17 21:33:03 tube-rivo-audio volumio[3431]: info: Plugin smart_inputs is not enabled Aug 17 21:33:03 tube-rivo-audio volumio[3431]: info: Loading plugin "tidal"... Aug 17 21:33:04 tube-rivo-audio volumio-remote-updater[2843]: [2024-08-17 21:33:04] [connect] Successful connection Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: Loading plugin "rivocontrol"... Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: Adding this device properties Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: Setting Additional Device Volumio Properties: [object Object] Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: Loading plugin "updater_comm"... Aug 17 21:33:05 tube-rivo-audio volumio[3431]: info: Loading plugin "cec_controller"... Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Applying required configuration parameters for plugin cec_controller Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Plugin mpdemulation is not enabled Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Loading plugin "rest_api"... Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Loading plugin "websocket"... Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Starting Socket.io Server version 2.3.0 Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Loading plugin "fusiondsp"... Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Applying required configuration parameters for plugin fusiondsp Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: Loading i18n strings for locale en Aug 17 21:33:06 tube-rivo-audio volumio[3431]: Updating browse sources language Aug 17 21:33:06 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::initPlayerControls Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: Express server listening on port 3000 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: [Metrics] WebUI: 18s 878.37ms Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::resetVolumioState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::getcurrentVolume Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioRetrievevolume Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::pushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioPushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Completed loading Core Plugins Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Preparing to generate the ALSA configuration file Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Reading ALSA contributions from plugins. Aug 17 21:33:07 tube-rivo-audio volumio-remote-updater[2843]: [2024-08-17 21:33:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723905184 101 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 17 21:33:07 tube-rivo-audio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Reloading queue from file Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::setRepeat false single undefined Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::pushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioPushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::setRandom false Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreStateMachine::pushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioPushState Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Asound.conf file unchanged, so no further update is needed Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Output device has changed, restarting MPD Aug 17 21:33:07 tube-rivo-audio sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: ___________ START PLUGINS ___________ Aug 17 21:33:07 tube-rivo-audio sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio sudo[3566]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: ControllerMpd::onStart: Initializing MPD Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Creating MPD Configuration file Aug 17 21:33:07 tube-rivo-audio sudo[3568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 21:33:07 tube-rivo-audio sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 21:33:07 tube-rivo-audio sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 21:33:07 tube-rivo-audio systemd[1]: Listening on mpd.socket. Aug 17 21:33:07 tube-rivo-audio sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio systemd[1]: Starting Music Player Daemon... Aug 17 21:33:07 tube-rivo-audio sudo[3572]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 21:33:07 tube-rivo-audio sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding METAVOLUMIO REST API Endpoints Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: [1723905187731] CoreMusicLibrary::Adding element Last_100 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Streaming services startup Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Starting Streaming Daemon Aug 17 21:33:07 tube-rivo-audio sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Tube Rivo Audio Aug 17 21:33:07 tube-rivo-audio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 17 21:33:07 tube-rivo-audio sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio systemd[1]: mpd.service: Succeeded. Aug 17 21:33:07 tube-rivo-audio systemd[1]: Stopped Music Player Daemon. Aug 17 21:33:07 tube-rivo-audio systemd[1]: Starting Music Player Daemon... Aug 17 21:33:07 tube-rivo-audio sudo[3587]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio sudo[3591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 17 21:33:07 tube-rivo-audio sudo[3591]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio sudo[3591]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: [1723905187866] CoreMusicLibrary::Adding element Webradio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Initializing BBC Radios Aug 17 21:33:07 tube-rivo-audio sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 17 21:33:07 tube-rivo-audio sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio sudo[3590]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 21:33:07 tube-rivo-audio sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:07 tube-rivo-audio sudo[3599]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio sudo[3590]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 17 21:33:07 tube-rivo-audio sudo[3590]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:33:07 tube-rivo-audio volumio[3431]: error: Hi Res Audio Failed Login: Missing Login Data Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 17 21:33:07 tube-rivo-audio volumio[3431]: info: Touch Event Listener Process Starting Aug 17 21:33:08 tube-rivo-audio kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 17 21:33:08 tube-rivo-audio kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 17 21:33:08 tube-rivo-audio kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Refreshing TIDAL token Aug 17 21:33:08 tube-rivo-audio sudo[3615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 17 21:33:08 tube-rivo-audio sudo[3615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3615]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 17 21:33:08 tube-rivo-audio sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3624]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio sudo[3627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 17 21:33:08 tube-rivo-audio sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3627]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 17 21:33:08 tube-rivo-audio sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3630]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio sudo[3633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 17 21:33:08 tube-rivo-audio sudo[3633]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3633]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Apply VIM3L Onboard LEDs Settings Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Turning On Onboard LEDs Aug 17 21:33:08 tube-rivo-audio sudo[3637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 17 21:33:08 tube-rivo-audio sudo[3637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Starting CEC Event listener Aug 17 21:33:08 tube-rivo-audio sudo[3637]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Loading i18n strings for locale en Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: FusionDsp - mixtype--------------------- None Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Volumio Calling Home Aug 17 21:33:08 tube-rivo-audio sudo[3641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 17 21:33:08 tube-rivo-audio sudo[3641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:08 tube-rivo-audio sudo[3641]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Preparing to generate the ALSA configuration file Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Stopping AccessToken refresher cron for QOBUZ Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: AccessToken refresher cron started for QOBUZ Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Adding QOBUZ REST API Endpoints Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Discovery: adding 1dc99a61-aea2-484f-a46a-3e4958b3c4a9 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Discovery: Found device Tube Rivo Audio Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioGetState Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Serial port opened successfully Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Sending serial start messages Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: MP1 GPIO: Signalled system ready via GPIO Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Reading ALSA contributions from plugins. Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Discovery: this is already registered, 1dc99a61-aea2-484f-a46a-3e4958b3c4a9 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Discovery: Found device Tube Rivo Audio Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioGetState Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Touch Event Listener Process Closed Aug 17 21:33:08 tube-rivo-audio volumio[3431]: error: Cannot start Volumio Streaming Daemon Aug 17 21:33:08 tube-rivo-audio volumio[3431]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 17 21:33:08 tube-rivo-audio volumio[3431]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: Bluetooth name changed to Tube Rivo Audio Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: MPD Permissions set Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: MPD Permissions set Aug 17 21:33:08 tube-rivo-audio volumio[3431]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:08 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: Volumio called home Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioGetState Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::volumioGetState Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CorePlayQueue::getTrack 0 Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: Asound.conf file unchanged, so no further update is needed Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: Output device has changed, restarting MPD Aug 17 21:33:09 tube-rivo-audio sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:09 tube-rivo-audio sudo[3663]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:09 tube-rivo-audio sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 21:33:09 tube-rivo-audio sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: MPD Permissions set Aug 17 21:33:09 tube-rivo-audio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 17 21:33:09 tube-rivo-audio systemd[1]: mpd.service: Succeeded. Aug 17 21:33:09 tube-rivo-audio systemd[1]: Stopped Music Player Daemon. Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio systemd[1]: Starting Music Player Daemon... Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 21:33:09 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:09 tube-rivo-audio sudo[3668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 21:33:09 tube-rivo-audio sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:09 tube-rivo-audio sudo[3668]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 17 21:33:09 tube-rivo-audio sudo[3668]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: Access Token successfully retrieved Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: camilladsp service started and running in background, instance 1 Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 21:33:10 tube-rivo-audio volumio[3431]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 17 21:33:10 tube-rivo-audio volumio[3431]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 17 21:33:10 tube-rivo-audio volumio[3431]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: FusionDsp loaded Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 21:33:10 tube-rivo-audio sudo[3688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 17 21:33:10 tube-rivo-audio sudo[3688]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: Adding Signal Path Element [object Object] Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: Adding fusiondspeq DSP Signal Path Element Aug 17 21:33:10 tube-rivo-audio volumio[3431]: info: FusionDsp - ---- installed callbackRead Aug 17 21:33:10 tube-rivo-audio volumio[3431]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 21:33:10 tube-rivo-audio sudo[3688]: pam_unix(sudo:session): session closed for user root Aug 17 21:33:10 tube-rivo-audio volumio[3431]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 17 21:33:10 tube-rivo-audio volumio[3431]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Aug 17 21:33:10 tube-rivo-audio volumio[3431]: at onErrorNT (internal/child_process.js:472:16) Aug 17 21:33:10 tube-rivo-audio volumio[3431]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Aug 17 21:33:10 tube-rivo-audio volumio[3431]: errno: -2, Aug 17 21:33:10 tube-rivo-audio volumio[3431]: code: 'ENOENT', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: spawnargs: [ Aug 17 21:33:10 tube-rivo-audio volumio[3431]: '-p', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: 9876, Aug 17 21:33:10 tube-rivo-audio volumio[3431]: '-o', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: '/tmp/camilladsp.log', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: '-l', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: 'warn', Aug 17 21:33:10 tube-rivo-audio volumio[3431]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 17 21:33:10 tube-rivo-audio volumio[3431]: ] Aug 17 21:33:10 tube-rivo-audio volumio[3431]: } Aug 17 21:33:10 tube-rivo-audio volumio[3431]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 21:33:11 tube-rivo-audio kernel: fb: mem_free_work, free memory: addr:800000 Aug 17 21:33:11 tube-rivo-audio sudo[3699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 21:32 Aug 17 21:33:11 tube-rivo-audio sudo[3699]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"