-- Logs begin at Fri 2024-04-26 20:35:05 CEST, end at Sat 2024-04-27 19:26:22 CEST. -- Apr 27 19:25:19 rivo volumio[5458]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 27 19:25:19 rivo volumio[5458]: info: MyVolumio login type: Token Apr 27 19:25:19 rivo volumio[5458]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 27 19:25:20 rivo volumio[5458]: info: MyVolumio token set successfully Apr 27 19:25:20 rivo volumio[5458]: info: MYVOLUMIO: Adding device Apr 27 19:25:20 rivo volumio[5458]: info: MYVOLUMIO: Evaluating Server Apr 27 19:25:20 rivo volumio[5458]: info: MyVolumio Plan changed: premium Apr 27 19:25:20 rivo volumio[5458]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 27 19:25:20 rivo volumio[5458]: info: Removing browser output: myVolumio user plan is not superstar Apr 27 19:25:20 rivo volumio[5458]: info: Removing audio output: Apr 27 19:25:20 rivo volumio[5458]: info: MYVOLUMIO: Adding device Apr 27 19:25:20 rivo volumio[5458]: info: MYVOLUMIO: Evaluating Server Apr 27 19:25:20 rivo volumio[5458]: info: Remote config written successfully Apr 27 19:25:20 rivo volumio[5458]: info: Starting Tunnel 1 Apr 27 19:25:20 rivo volumio[5458]: info: Starting Tunnel Connection Checker Apr 27 19:25:20 rivo sudo[4244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 27 19:25:20 rivo sudo[4244]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:21 rivo systemd[1]: Started MyVolumio SSH Tunnel. Apr 27 19:25:21 rivo sudo[4244]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:21 rivo autossh[4263]: port set to 0, monitoring disabled Apr 27 19:25:21 rivo autossh[4263]: starting ssh (count 1) Apr 27 19:25:21 rivo autossh[4263]: ssh child pid is 4279 Apr 27 19:25:21 rivo volumiossh-tunnel[4248]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Apr 27 19:25:21 rivo volumio[5458]: info: MYVolumio Device enabled Apr 27 19:25:21 rivo volumio[5458]: info: MyVolumio status changed Apr 27 19:25:21 rivo volumio[5458]: info: Streaming services startup Apr 27 19:25:21 rivo volumio[5458]: info: Starting Streaming Daemon Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 27 19:25:21 rivo volumio[5458]: info: Setting Geolocation for MyVolumio to eu7 Apr 27 19:25:21 rivo volumio[5458]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:21 rivo volumio[5458]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:21 rivo volumio[5458]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:21 rivo sudo[4297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 27 19:25:21 rivo sudo[4297]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:21 rivo volumio[5458]: info: Remote SSH Started Apr 27 19:25:21 rivo sudo[4297]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:21 rivo volumio[5458]: error: Cannot start Volumio Streaming Daemon Apr 27 19:25:21 rivo volumio[5458]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 27 19:25:21 rivo volumio[5458]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 27 19:25:21 rivo volumio[5458]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 27 19:25:21 rivo volumio[5458]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:25:21 rivo volumio[5458]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 19:25:21 rivo volumio[5458]: Error: Unable to resolve or reject the same promise twice Apr 27 19:25:21 rivo volumio[5458]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 27 19:25:21 rivo volumio[5458]: at Array. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5676) Apr 27 19:25:21 rivo volumio[5458]: at Promise.reject (/volumio/node_modules/kew/kew.js:204:26) Apr 27 19:25:21 rivo volumio[5458]: at nextTickCallback (/volumio/node_modules/kew/kew.js:49:11) Apr 27 19:25:21 rivo volumio[5458]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 27 19:25:21 rivo volumio[5458]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 19:25:22 rivo sudo[4308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 19:24 Apr 27 19:25:22 rivo sudo[4308]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:22 rivo sudo[4308]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:22 rivo volumio-remote-updater[4732]: [2024-04-27 19:25:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 27 19:25:22 rivo volumio-remote-updater[4732]: [2024-04-27 19:25:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 27 19:25:22 rivo vtcs[21456]: [2024-04-27 19:25:22.774] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Apr 27 19:25:22 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 27 19:25:22 rivo sudo[7288]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:22 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 27 19:25:22 rivo systemd[1]: Started dynamicswap service. Apr 27 19:25:22 rivo systemd[1]: dynamicswap.service: Succeeded. Apr 27 19:25:23 rivo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 27 19:25:23 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 27 19:25:23 rivo systemd[1]: Started dynamicswap service. Apr 27 19:25:23 rivo systemd[1]: Stopped Volumio Backend Module. Apr 27 19:25:23 rivo systemd[1]: Started Volumio Backend Module. Apr 27 19:25:23 rivo systemd[1]: dynamicswap.service: Succeeded. Apr 27 19:25:25 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:25 rivo volumio[4345]: info: ----- Volumio3 ---- Apr 27 19:25:25 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:25 rivo volumio[4345]: info: ----- System startup ---- Apr 27 19:25:25 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:26 rivo volumio[4345]: info: MYVOLUMIO Environment detected Apr 27 19:25:26 rivo volumio[4345]: info: Plugin folders cleanup Apr 27 19:25:26 rivo volumio[4345]: info: Scanning into folder /volumio/app/plugins/ Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category audio_interface Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category miscellanea Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category music_service Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category plugins.json Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category system_controller Apr 27 19:25:26 rivo volumio[4345]: info: Scanning category user_interface Apr 27 19:25:26 rivo volumio[4345]: info: Scanning into folder /data/plugins/ Apr 27 19:25:26 rivo volumio[4345]: info: Plugin folders cleanup completed Apr 27 19:25:26 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:26 rivo volumio[4345]: info: ----- Core plugins startup ---- Apr 27 19:25:26 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:26 rivo volumio[4345]: info: Loading plugins from folder /volumio/app/plugins/ Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin bluetooth to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin multiroom to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin upnp to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin metavolumio to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin cd_controller to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin raat to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 27 19:25:26 rivo volumio[4345]: info: Loading plugins from folder /data/plugins/ Apr 27 19:25:26 rivo volumio[4345]: info: Loading plugin "system"... Apr 27 19:25:26 rivo volumio[4345]: info: Loading plugin "appearance"... Apr 27 19:25:27 rivo volumio-remote-updater[4732]: [2024-04-27 19:25:27] [connect] Successful connection Apr 27 19:25:27 rivo volumio[4345]: info: Loading plugin "network"... Apr 27 19:25:27 rivo volumio[4345]: info: Refreshing Cached IP Addresses Apr 27 19:25:27 rivo sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 19:25:27 rivo sudo[4456]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:27 rivo sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 19:25:27 rivo volumio[4345]: info: Loading plugin "services"... Apr 27 19:25:27 rivo sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:27 rivo volumio[4345]: info: Loading plugin "alsa_controller"... Apr 27 19:25:27 rivo sudo[4456]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:27 rivo sudo[4458]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:27 rivo sudo[4463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 27 19:25:27 rivo sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:28 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "wizard"... Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "networkfs"... Apr 27 19:25:28 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e701000 drv_status 0x0 e_type 69 e_states 8 Apr 27 19:25:28 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e701000 drv_status 0x0 e_type 69 e_states 8 Apr 27 19:25:28 rivo volumio[4345]: info: Starting Udev Watcher for removable devices Apr 27 19:25:28 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e701000 drv_status 0x0 e_type 69 e_states 8 Apr 27 19:25:28 rivo volumio[4345]: info: Ignoring mount for partition: BOOT Apr 27 19:25:28 rivo volumio[4345]: info: Ignoring mount for partition: volumio Apr 27 19:25:28 rivo volumio[4345]: info: Ignoring mount for partition: volumio_data Apr 27 19:25:28 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "volumio_command_line_client"... Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "upnp"... Apr 27 19:25:28 rivo volumio[4345]: info: [1714238728218] Starting Upmpd Daemon Apr 27 19:25:28 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "my_music"... Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "mpd"... Apr 27 19:25:28 rivo volumio[4345]: info: Loading plugin "upnp_browser"... Apr 27 19:25:29 rivo volumio[4345]: info: Loading plugin "bluetooth"... Apr 27 19:25:29 rivo volumio[4345]: info: [1714238729226] Starting BluetoothController Apr 27 19:25:29 rivo volumio[4345]: info: Loading plugin "alarm-clock"... Apr 27 19:25:29 rivo volumio[4345]: info: Loading plugin "manifestui"... Apr 27 19:25:29 rivo volumio[4345]: info: Loading plugin "metavolumio"... Apr 27 19:25:30 rivo volumio[4345]: info: Loading plugin "airplay_emulation"... Apr 27 19:25:30 rivo volumio[4345]: info: Starting Shairport Sync Apr 27 19:25:30 rivo volumio[4345]: info: Loading plugin "cd_controller"... Apr 27 19:25:30 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e701000 drv_status 0x0 e_type 69 e_states 0 Apr 27 19:25:31 rivo sudo[4463]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:31 rivo volumio[4345]: info: Loading plugin "last_100"... Apr 27 19:25:31 rivo volumio[4345]: info: Loading plugin "raat"... Apr 27 19:25:31 rivo volumio[4345]: info: RAAT Plugin loaded Apr 27 19:25:31 rivo volumio[4345]: info: Adding restartRAATSocket REST API Endpoint Apr 27 19:25:31 rivo volumio[4345]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 27 19:25:31 rivo volumio[4345]: info: Loading plugin "streaming_services"... Apr 27 19:25:32 rivo volumio[4345]: info: Starting Streaming Service Transparent Proxy Apr 27 19:25:32 rivo volumio[4345]: info: Loading plugin "tidalconnect"... Apr 27 19:25:32 rivo volumio[4345]: info: Loading plugin "webradio"... Apr 27 19:25:32 rivo volumio[4345]: info: Loading plugin "i2s_dacs"... Apr 27 19:25:33 rivo volumio[4345]: info: I2S DAC not set, start Auto-detection Apr 27 19:25:33 rivo volumio[4345]: info: Loading plugin "volumiodiscovery"... Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 19:25:33 rivo node[4345]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** For more information see Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 19:25:33 rivo volumio[4345]: *** WARNING *** For more information see Apr 27 19:25:33 rivo node[4345]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 19:25:33 rivo node[4345]: *** WARNING *** For more information see Apr 27 19:25:33 rivo node[4345]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 27 19:25:33 rivo node[4345]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 27 19:25:33 rivo node[4345]: *** WARNING *** For more information see Apr 27 19:25:33 rivo volumio[4345]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 27 19:25:33 rivo volumio[4345]: info: Discovery: Started advertising with name: Rivo Apr 27 19:25:33 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 19:25:33 rivo volumio[4345]: info: Loading plugin "multiroom"... Apr 27 19:25:34 rivo volumio[4345]: STREAMING PROXY: Starting server on port 3245 Apr 27 19:25:34 rivo volumio[4345]: Node JS runtime: 14 Apr 27 19:25:34 rivo volumio[4345]: info: Applying required configuration parameters for plugin multiroom Apr 27 19:25:34 rivo sudo[4487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 27 19:25:34 rivo sudo[4487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:34 rivo sudo[4487]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:35 rivo volumio[4345]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Apr 27 19:25:35 rivo volumio[4345]: info: MRS: MultiRoom plugin initialized Apr 27 19:25:35 rivo volumio[4345]: info: MRS: STOPPING SNAPCLIENT Apr 27 19:25:35 rivo volumio[4345]: info: MRS: Snap server stop Apr 27 19:25:35 rivo volumio[4345]: info: MRS: STOPPING volumioStreaming Apr 27 19:25:35 rivo sudo[4503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 27 19:25:35 rivo sudo[4503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:35 rivo sudo[4505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 27 19:25:35 rivo sudo[4505]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:35 rivo volumio[4345]: info: Loading plugin "outputs"... Apr 27 19:25:35 rivo sudo[4509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 27 19:25:35 rivo sudo[4509]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:35 rivo sudo[4503]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:35 rivo sudo[4505]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:35 rivo volumio[4345]: info: Loading plugin "albumart"... Apr 27 19:25:35 rivo sudo[4512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 27 19:25:35 rivo sudo[4512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:35 rivo sudo[4512]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:35 rivo sudo[4509]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:35 rivo volumio[4345]: info: Plugin example_plugin is not enabled Apr 27 19:25:35 rivo volumio[4345]: info: Loading plugin "hi_res_audio"... Apr 27 19:25:35 rivo volumio[4345]: Forking 3 albumart workers Apr 27 19:25:37 rivo volumio[4345]: Starting albumart workers Apr 27 19:25:37 rivo volumio[4345]: info: Applying required configuration parameters for plugin hi_res_audio Apr 27 19:25:37 rivo volumio[4345]: info: Loading plugin "inputs"... Apr 27 19:25:37 rivo volumio[4345]: Starting albumart workers Apr 27 19:25:37 rivo volumio[4345]: Starting albumart workers Apr 27 19:25:38 rivo volumio[4345]: info: Loading plugin "qobuz"... Apr 27 19:25:40 rivo volumio[4345]: info: Loading plugin "smart_inputs"... Apr 27 19:25:40 rivo volumio-remote-updater[4732]: [2024-04-27 19:25:40] [connect] Successful connection Apr 27 19:25:40 rivo volumio[4345]: info: Loading plugin "tidal"... Apr 27 19:25:41 rivo volumio[4345]: info: Loading plugin "rivocontrol"... Apr 27 19:25:42 rivo volumio[4345]: info: Adding this device properties Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 27 19:25:42 rivo volumio[4345]: info: Setting Additional Device Volumio Properties: [object Object] Apr 27 19:25:42 rivo volumio[4345]: info: Loading plugin "updater_comm"... Apr 27 19:25:42 rivo volumio[4345]: info: Loading plugin "cec_controller"... Apr 27 19:25:42 rivo volumio[4345]: info: Applying required configuration parameters for plugin cec_controller Apr 27 19:25:42 rivo volumio[4345]: info: Plugin mpdemulation is not enabled Apr 27 19:25:42 rivo volumio[4345]: info: Loading plugin "rest_api"... Apr 27 19:25:42 rivo volumio[4345]: info: Loading plugin "websocket"... Apr 27 19:25:42 rivo volumio[4345]: info: Loading i18n strings for locale it Apr 27 19:25:42 rivo volumio[4345]: Updating browse sources language Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::initPlayerControls Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 19:25:42 rivo volumio[4345]: Express server listening on port 3000 Apr 27 19:25:42 rivo volumio[4345]: [Metrics] WebUI: 18s 69.80ms Apr 27 19:25:42 rivo volumio[4345]: info: CoreStateMachine::resetVolumioState Apr 27 19:25:42 rivo volumio[4345]: info: CoreStateMachine::getcurrentVolume Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 19:25:42 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:43 rivo sudo[4577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 19:25:43 rivo sudo[4577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:43 rivo sudo[4577]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:43 rivo volumio[4345]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 27 19:25:43 rivo volumio[4345]: info: Completed loading Core Plugins Apr 27 19:25:43 rivo sudo[4581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 19:25:43 rivo volumio[4345]: info: Preparing to generate the ALSA configuration file Apr 27 19:25:43 rivo sudo[4581]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:43 rivo sudo[4581]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:43 rivo volumio[4345]: info: MRS: Removed streaming files Apr 27 19:25:43 rivo volumio[4345]: info: MRS: volumioStreaming STOPPED Apr 27 19:25:43 rivo volumio[4345]: info: MRS: SNAPSERVER STOPPED Apr 27 19:25:43 rivo volumio[4345]: info: MRS: SNAPCLIENT STOPPED Apr 27 19:25:43 rivo volumio[4345]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 27 19:25:43 rivo volumio[4345]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 27 19:25:43 rivo volumio[4345]: info: Reading ALSA contributions from plugins. Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 1 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 2 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 3 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 4 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 5 Apr 27 19:25:43 rivo volumio-remote-updater[4732]: [2024-04-27 19:25:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714238740 101 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Apr 27 19:25:43 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Apr 27 19:25:43 rivo volumio[4345]: info: VolumeController:: Volume=25 Mute =false Apr 27 19:25:43 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:43 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:43 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:43 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:43 rivo volumio[4345]: info: CoreStateMachine::updateTrackBlock Apr 27 19:25:43 rivo volumio[4345]: info: CorePlayQueue::getTrackBlock Apr 27 19:25:43 rivo volumio[4345]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 19:25:43 rivo volumio[4345]: info: Reloading queue from file Apr 27 19:25:43 rivo volumio[4345]: info: CoreStateMachine::setRepeat true single undefined Apr 27 19:25:43 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:44 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:44 rivo volumio[4345]: info: CoreStateMachine::setRandom false Apr 27 19:25:44 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:44 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:44 rivo volumio[4345]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 8 Apr 27 19:25:44 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Apr 27 19:25:44 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 10 Apr 27 19:25:44 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Apr 27 19:25:44 rivo sudo[4596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 27 19:25:44 rivo sudo[4596]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo volumio[4345]: info: VolumeController:: Volume=25 Mute =false Apr 27 19:25:44 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:44 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:44 rivo sudo[4596]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:44 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 12 Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 19:25:44 rivo volumio[4345]: info: Upmpdcli Daemon Started Apr 27 19:25:44 rivo volumio[4345]: info: Asound.conf file unchanged, so no further update is needed Apr 27 19:25:44 rivo volumio[4345]: info: Output device has changed, restarting MPD Apr 27 19:25:44 rivo sudo[4599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 19:25:44 rivo sudo[4599]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo volumio[4345]: info: Output device has changed, restarting Shairport Sync Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:44 rivo sudo[4599]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:44 rivo sudo[4602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 19:25:44 rivo sudo[4602]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo systemd[1]: Stopping Music Player Daemon... Apr 27 19:25:44 rivo systemd[1]: mpd.service: Succeeded. Apr 27 19:25:44 rivo systemd[1]: Stopped Music Player Daemon. Apr 27 19:25:44 rivo volumio[4345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 19:25:44 rivo volumio[4345]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:44 rivo systemd[1]: Starting Music Player Daemon... Apr 27 19:25:44 rivo volumio[4345]: info: ___________ START PLUGINS ___________ Apr 27 19:25:44 rivo volumio[4345]: info: ControllerMpd::onStart: Initializing MPD Apr 27 19:25:44 rivo volumio[4345]: info: Creating MPD Configuration file Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 19:25:44 rivo volumio[4345]: info: [1714238744696] CoreMusicLibrary::Adding element Server multimediali Apr 27 19:25:44 rivo sudo[4629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:44 rivo sudo[4629]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo sudo[4629]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:44 rivo sudo[4636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 19:25:44 rivo sudo[4636]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 19:25:44 rivo volumio[4345]: info: Adding Manifest REST API Endpoints Apr 27 19:25:44 rivo volumio[4345]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Apr 27 19:25:44 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 27 19:25:44 rivo systemd[1]: mpd.service: Succeeded. Apr 27 19:25:44 rivo volumio[4345]: info: Adding METAVOLUMIO REST API Endpoints Apr 27 19:25:44 rivo volumio[4345]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 27 19:25:44 rivo volumio[4345]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 27 19:25:44 rivo systemd[1]: Stopped Music Player Daemon. Apr 27 19:25:44 rivo volumio[4345]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 27 19:25:44 rivo volumio[4345]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:44 rivo systemd[1]: Starting Music Player Daemon... Apr 27 19:25:44 rivo sudo[4654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Apr 27 19:25:44 rivo sudo[4654]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:44 rivo sudo[4654]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:44 rivo volumio[4345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:44 rivo volumio[4345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 19:25:44 rivo volumio[4345]: info: Preparing CD Folders Apr 27 19:25:44 rivo volumio[4345]: info: Adding CD REST API Endpoints Apr 27 19:25:44 rivo volumio[4345]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 27 19:25:44 rivo volumio[4345]: info: Starting UDEV Watcher for CD Apr 27 19:25:44 rivo volumio[4345]: info: Detecting CD presence with UDEV Apr 27 19:25:44 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 19:25:45 rivo volumio[4345]: info: [1714238745028] CoreMusicLibrary::Adding element Last_100 Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:45 rivo volumio[4345]: info: Starting RAAT Plugin Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 27 19:25:45 rivo volumio[4345]: info: Additional UI Settings Added for plugin music_service/raat Apr 27 19:25:45 rivo volumio[4345]: info: Registering DSP Elements listener and retrieving current ones Apr 27 19:25:45 rivo volumio[4345]: info: Additional DSP elements updated Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: Updating RAAT Signal Path Apr 27 19:25:45 rivo volumio[4345]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 19:25:45 rivo volumio[4345]: info: Not Reporting Auto name since its the default one Apr 27 19:25:45 rivo volumio[4345]: info: Streaming services startup Apr 27 19:25:45 rivo volumio[4345]: info: Starting Streaming Daemon Apr 27 19:25:45 rivo sudo[4688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 27 19:25:45 rivo sudo[4688]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4688]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 19:25:45 rivo volumio[4345]: info: [1714238745277] CoreMusicLibrary::Adding element Webradio Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 27 19:25:45 rivo sudo[4698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 27 19:25:45 rivo sudo[4698]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo volumio[4345]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 27 19:25:45 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:45 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 27 19:25:45 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 27 19:25:45 rivo systemd[1]: vtcs.service: Succeeded. Apr 27 19:25:45 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 27 19:25:45 rivo sudo[4698]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo volumio[4345]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 27 19:25:45 rivo volumio[4345]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 27 19:25:45 rivo volumio[4345]: info: Initializing Serial Communication on port /dev/ttyS3 Apr 27 19:25:45 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Apr 27 19:25:45 rivo volumio[4345]: info: Refreshing QOBUZ token Apr 27 19:25:45 rivo volumio[4345]: info: Adding inputs REST Endpoints Apr 27 19:25:45 rivo volumio[4345]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 27 19:25:45 rivo volumio[4345]: info: Scanning Audio Inputs Apr 27 19:25:45 rivo volumio[4345]: info: Checking against Known Cards name Apr 27 19:25:45 rivo volumio[4345]: info: Checking against Known Cards name Apr 27 19:25:45 rivo volumio[4345]: info: Checking against Known Cards name Apr 27 19:25:45 rivo volumio[4345]: info: Checking against Known Cards name Apr 27 19:25:45 rivo volumio[4345]: info: Checking against Known Cards name Apr 27 19:25:45 rivo volumio[4345]: info: Adding Server instance for streaming Apr 27 19:25:45 rivo sudo[4708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Apr 27 19:25:45 rivo sudo[4708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4708]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo sudo[4711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Apr 27 19:25:45 rivo sudo[4711]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4711]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo sudo[4714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Apr 27 19:25:45 rivo sudo[4714]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4714]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo sudo[4717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Apr 27 19:25:45 rivo sudo[4717]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4717]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo volumio[4345]: info: Apply VIM3L Onboard LEDs Settings Apr 27 19:25:45 rivo volumio[4345]: info: Turning On Onboard LEDs Apr 27 19:25:45 rivo sudo[4721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 27 19:25:45 rivo volumio[4345]: info: Starting CEC Event listener Apr 27 19:25:45 rivo sudo[4721]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4721]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:45 rivo volumio[4345]: info: Volumio Calling Home Apr 27 19:25:45 rivo sudo[4727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 27 19:25:45 rivo sudo[4727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:45 rivo sudo[4727]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:46 rivo volumio[4345]: (node:4345) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 27 19:25:46 rivo volumio[4345]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 27 19:25:46 rivo volumio[4345]: info: Stopping AccessToken refresher cron Apr 27 19:25:46 rivo volumio[4345]: info: AccessToken refresher cron started Apr 27 19:25:46 rivo volumio[4345]: info: Adding TIDAL REST API Endpoints Apr 27 19:25:46 rivo volumio[4345]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 27 19:25:46 rivo volumio[4345]: info: Discovery: adding 8dc549a8-0193-415b-b075-3ef2d5375bbf Apr 27 19:25:46 rivo volumio[4345]: info: Discovery: Found device Rivo Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: MRS: Pushing multiroomSync output for this device Apr 27 19:25:46 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:46 rivo volumio[4345]: info: Adding audio output: Apr 27 19:25:46 rivo volumio[4345]: info: Adding audio output: Apr 27 19:25:46 rivo volumio[4345]: info: Discovery: this is already registered, 8dc549a8-0193-415b-b075-3ef2d5375bbf Apr 27 19:25:46 rivo volumio[4345]: info: Discovery: Found device Rivo Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 13 Apr 27 19:25:46 rivo volumio[4345]: info: Serial port opened successfully Apr 27 19:25:46 rivo volumio[4345]: info: Sending serial start messages Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:46 rivo volumio[4345]: info: MP1 GPIO: Signalled system ready via GPIO Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 19:25:46 rivo volumio[4345]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 27 19:25:46 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:46 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: Listing playlists Apr 27 19:25:46 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 14 Apr 27 19:25:46 rivo volumio[4345]: error: Cannot start Volumio Streaming Daemon Apr 27 19:25:46 rivo volumio[4345]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 27 19:25:46 rivo volumio[4345]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 27 19:25:46 rivo volumio[4345]: info: Bluetooth name changed to Rivo Apr 27 19:25:46 rivo volumio[4345]: info: MPD Permissions set Apr 27 19:25:46 rivo volumio[4345]: info: MPD Permissions set Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 27 19:25:46 rivo volumio[4345]: info: Setting Device Volume Override Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 19:25:46 rivo volumio[4345]: info: Updating Volume Controller Parameters: Device: 5 Name: iFi (by AMR) HD USB Audio Mixer: iFi (by AMR) HD USB Audio Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 19:25:46 rivo volumio[4345]: info: Disabling external Volume Control Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:46 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:46 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:46 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:25:46 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:46 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 15 Apr 27 19:25:46 rivo volumio[4345]: info: Volumio called home Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 27 19:25:46 rivo volumio[4345]: info: Setting Additional System Software info: Hardware Revision: 2.1 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 27 19:25:46 rivo volumio[4345]: info: Setting HW Firmware info: undefined Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 27 19:25:46 rivo volumio[4345]: info: Setting HW Version info: 2.1 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 27 19:25:46 rivo volumio[4345]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.3 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 27 19:25:46 rivo volumio[4345]: info: Setting HW Firmware info: 0.3.3 Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 27 19:25:46 rivo volumio[4345]: info: Setting HW Version info: 2.1 Apr 27 19:25:46 rivo volumio[4345]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 27 19:25:46 rivo volumio[4345]: info: MCU Signalled Headphone Mode Disabled Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 27 19:25:46 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:46 rivo volumio[4345]: info: Updating RAAT Signal Path Apr 27 19:25:46 rivo volumio[4345]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 27 19:25:47 rivo volumio[4345]: info: MCU Signalled Sleep Mode Disabled Apr 27 19:25:47 rivo volumio[4345]: info: Enabling Advanced system settings configuration Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 27 19:25:47 rivo volumio[4345]: info: Additional UI Settings Added for plugin music_service/inputs Apr 27 19:25:47 rivo volumio[4345]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 27 19:25:47 rivo sudo[4747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 27 19:25:47 rivo sudo[4747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:47 rivo mpd[4655]: Apr 27 19:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 19:25:47 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 16 Apr 27 19:25:47 rivo systemd[1]: Started Music Player Daemon. Apr 27 19:25:47 rivo sudo[4602]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:47 rivo volumio[4345]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17 Apr 27 19:25:47 rivo sudo[4636]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:47 rivo kernel: fb: osd[0] enable: 1 (Xorg) Apr 27 19:25:47 rivo sudo[4747]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 27 19:25:47 rivo volumio[4345]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 27 19:25:47 rivo sudo[4773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 27 19:25:47 rivo sudo[4773]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:47 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 18 Apr 27 19:25:47 rivo sudo[4773]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:47 rivo sudo[4775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 27 19:25:47 rivo sudo[4775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:47 rivo systemd[1]: Stopping RAAT DAEMON... Apr 27 19:25:47 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 27 19:25:47 rivo systemd[1]: raat-daemon.service: Succeeded. Apr 27 19:25:47 rivo systemd[1]: Stopped RAAT DAEMON. Apr 27 19:25:47 rivo systemd[1]: Started RAAT DAEMON. Apr 27 19:25:47 rivo sudo[4775]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:47 rivo volumio[4345]: info: Executing endpoint metavolumio Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 19:25:47 rivo volumio[4345]: info: Executing endpoint metavolumio Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 19:25:47 rivo volumio[4345]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 19 Apr 27 19:25:47 rivo volumio[4345]: info: Executing endpoint metavolumio Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 19:25:47 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 20 Apr 27 19:25:47 rivo volumio[4345]: info: Access Token successfully retrieved Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 27 19:25:47 rivo volumio[4345]: info: [1714238747822] CoreMusicLibrary::Adding element QOBUZ Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:47 rivo volumio[4345]: Cannot find translation for source QOBUZ Apr 27 19:25:47 rivo volumio[4345]: info: Stopping AccessToken refresher cron for QOBUZ Apr 27 19:25:47 rivo volumio[4345]: info: AccessToken refresher cron started for QOBUZ Apr 27 19:25:47 rivo volumio[4345]: info: Adding QOBUZ REST API Endpoints Apr 27 19:25:47 rivo volumio[4345]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 27 19:25:47 rivo volumio[4345]: info: Raat Daemon started successfully Apr 27 19:25:47 rivo volumio[4345]: info: VolumeController:: Volume=25 Mute =false Apr 27 19:25:47 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:47 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:47 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:47 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:25:47 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:48 rivo sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 27 19:25:48 rivo sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 21 Apr 27 19:25:48 rivo sudo[4833]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo volumio[4345]: error: MPD error: The expression evaluated to a falsy value: Apr 27 19:25:48 rivo volumio[4345]: assert.ok(self.idling) Apr 27 19:25:48 rivo volumio[4345]: error: The expression evaluated to a falsy value: Apr 27 19:25:48 rivo volumio[4345]: assert.ok(self.idling) Apr 27 19:25:48 rivo volumio[4345]: info: Executing endpoint restartRAATSocket Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 27 19:25:48 rivo volumio[4345]: info: MPD running with PID4655 Apr 27 19:25:48 rivo volumio[4345]: ,establishing connection Apr 27 19:25:48 rivo volumio[4345]: info: Starting Shairport Sync Apr 27 19:25:48 rivo volumio[4345]: info: Starting Shairport Sync Apr 27 19:25:48 rivo sudo[4837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 19:25:48 rivo sudo[4837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo volumio[4345]: info: Starting Shairport Sync Apr 27 19:25:48 rivo sudo[4839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 19:25:48 rivo sudo[4839]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 19:25:48 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 27 19:25:48 rivo volumio[4345]: info: Adding Inputs via Serial API Apr 27 19:25:48 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo sudo[4837]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:48 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:48 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:25:48 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:48 rivo sudo[4843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 19:25:48 rivo sudo[4843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 19:25:48 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 27 19:25:48 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 27 19:25:48 rivo volumio[4345]: error: updateQueue error: null Apr 27 19:25:48 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo sudo[4839]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 19:25:48 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 27 19:25:48 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 27 19:25:48 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 19:25:48 rivo sudo[4843]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo volumio[4345]: error: Hi Res Audio Failed Login: [object Object] Apr 27 19:25:48 rivo volumio[4345]: info: Volumio BT Module successfully started Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::servicePushState Apr 27 19:25:48 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:48 rivo volumio[4345]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:48 rivo volumio[4345]: Cannot find translation for source QOBUZ Apr 27 19:25:48 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 22 Apr 27 19:25:48 rivo volumio[4345]: info: Shairport-Sync Started Apr 27 19:25:48 rivo volumio[4345]: Error adding Membership: Error: addMembership EINVAL Apr 27 19:25:48 rivo volumio[4345]: info: Shairport-Sync Started Apr 27 19:25:48 rivo volumio[4345]: info: Shairport-Sync Started Apr 27 19:25:48 rivo volumio[4345]: info: TidalConnect service stoped! Apr 27 19:25:48 rivo volumio[4345]: error: updateQueue error: null Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 23 Apr 27 19:25:48 rivo volumio[4345]: info: Turning Off Onboard LEDs Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 24 Apr 27 19:25:48 rivo sudo[4890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 27 19:25:48 rivo sudo[4890]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo sudo[4890]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 25 Apr 27 19:25:48 rivo sudo[4894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 27 19:25:48 rivo sudo[4894]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:48 rivo sudo[4894]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:48 rivo volumio[4345]: info: MCU Signalled Playback Inactive Apr 27 19:25:48 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 26 Apr 27 19:25:48 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:48 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: Onboard LEDs initialized Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 27 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 27 19:25:49 rivo volumio[4345]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 28 Apr 27 19:25:49 rivo sudo[4903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 27 19:25:49 rivo sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:49 rivo systemd[1]: Started Volumio Tidal Connect Service. Apr 27 19:25:49 rivo sudo[4903]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 29 Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 30 Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 31 Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 32 Apr 27 19:25:49 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 33 Apr 27 19:25:49 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:49 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 19:25:49 rivo volumio[4345]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 27 19:25:49 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:49 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: Listing playlists Apr 27 19:25:49 rivo volumio[4345]: info: Executing endpoint tc_getconfig Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 27 19:25:49 rivo vtcs[4905]: STARTING TidalConnect services, version: 1.3.0.19 Apr 27 19:25:49 rivo vtcs[4905]: STARTED TidalConnect services. Apr 27 19:25:49 rivo volumio[4345]: info: Executing endpoint tc_connect Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 27 19:25:49 rivo volumio[4345]: info: Connecting to TidalConnect Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::servicePushState Apr 27 19:25:49 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:49 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:25:49 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::servicePushState Apr 27 19:25:49 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:25:49 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:25:49 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:25:49 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:49 rivo volumio[4345]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Apr 27 19:25:50 rivo volumio[4345]: info: MRS: Getting audio outputs on start Apr 27 19:25:50 rivo volumio[4345]: info: MRS: Requesting all other devices output Apr 27 19:25:52 rivo volumio[4345]: info: TidalConnect service started! Apr 27 19:25:52 rivo volumio[4345]: info: Completed starting Core Plugins Apr 27 19:25:52 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:52 rivo volumio[4345]: info: ----- MyVolumio plugins startup ---- Apr 27 19:25:52 rivo volumio[4345]: info: ------------------------------------------- Apr 27 19:25:52 rivo volumio[4345]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 27 19:25:53 rivo volumio[4345]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 27 19:25:53 rivo sudo[4967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 19:25:53 rivo sudo[4967]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:53 rivo sudo[4967]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:53 rivo sudo[4969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 19:25:53 rivo sudo[4969]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:25:53 rivo sudo[4969]: pam_unix(sudo:session): session closed for user root Apr 27 19:25:53 rivo volumio[4345]: verbose: New Socket.io Connection to 192.168.0.253 from 192.168.0.19 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 34 Apr 27 19:25:53 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:53 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 19:25:53 rivo volumio[4345]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 27 19:25:53 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:53 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: Listing playlists Apr 27 19:25:53 rivo volumio[4345]: info: Received Get System Info Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 19:25:53 rivo volumio[4345]: info: Discovery: Getting this device information Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 19:25:53 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:25:53 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:25:55 rivo volumio[4345]: error: Failed request for metavolumio API Apr 27 19:25:55 rivo volumio[4345]: error: Failed request for metavolumio API Apr 27 19:25:55 rivo volumio[4345]: error: Failed request for metavolumio API Apr 27 19:25:55 rivo volumio[4345]: info: Checking for updated MCU Firmware Apr 27 19:25:55 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 19:25:55 rivo volumio[4345]: info: Firware on device is on latest version, no need to update Apr 27 19:25:57 rivo volumio[4345]: info: CoreCommandRouter::volumioGetQueue Apr 27 19:25:57 rivo volumio[4345]: info: CoreStateMachine::getQueue Apr 27 19:25:57 rivo volumio[4345]: info: CorePlayQueue::getQueue Apr 27 19:25:57 rivo volumio[4345]: info: Executing endpoint metavolumio Apr 27 19:25:57 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 19:25:57 rivo volumio[4345]: info: Executing endpoint metavolumio Apr 27 19:25:57 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 27 19:26:00 rivo volumio[4345]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 27 19:26:01 rivo volumio[4345]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 27 19:26:01 rivo volumio[4345]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 27 19:26:01 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:01 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:01 rivo volumio[4345]: info: Starting MyVolumio Remote Streaming Endpoints Apr 27 19:26:01 rivo volumio[4345]: info: MyVolumio login type: Token Apr 27 19:26:01 rivo volumio[4345]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 27 19:26:01 rivo volumio[4345]: 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' Apr 27 19:26:02 rivo volumio[4345]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 27 19:26:02 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 27 19:26:02 rivo volumio[4345]: info: CURURI: playlists Apr 27 19:26:02 rivo volumio[4345]: info: Listing playlists Apr 27 19:26:02 rivo volumio[4345]: info: Preload queue cleared Apr 27 19:26:02 rivo volumio[4345]: info: MyVolumio token set successfully Apr 27 19:26:02 rivo volumio[4345]: info: MYVOLUMIO: Adding device Apr 27 19:26:02 rivo volumio[4345]: info: MYVOLUMIO: Evaluating Server Apr 27 19:26:03 rivo volumio[4345]: info: MyVolumio Plan changed: premium Apr 27 19:26:03 rivo volumio[4345]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 27 19:26:03 rivo volumio[4345]: info: Removing browser output: myVolumio user plan is not superstar Apr 27 19:26:03 rivo volumio[4345]: info: Removing audio output: Apr 27 19:26:03 rivo volumio[4345]: info: MYVOLUMIO: Adding device Apr 27 19:26:03 rivo volumio[4345]: info: MYVOLUMIO: Evaluating Server Apr 27 19:26:03 rivo volumio[4345]: info: Remote config written successfully Apr 27 19:26:03 rivo volumio[4345]: info: Starting Tunnel 1 Apr 27 19:26:03 rivo volumio[4345]: info: Starting Tunnel Connection Checker Apr 27 19:26:03 rivo volumio[4345]: info: MYVolumio Device enabled Apr 27 19:26:03 rivo volumio[4345]: info: MyVolumio status changed Apr 27 19:26:03 rivo volumio[4345]: info: Streaming services startup Apr 27 19:26:03 rivo volumio[4345]: info: Starting Streaming Daemon Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 27 19:26:04 rivo sudo[5060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 27 19:26:04 rivo sudo[5060]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:26:04 rivo volumio[4345]: info: Setting Geolocation for MyVolumio to eu7 Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo sudo[5060]: pam_unix(sudo:session): session closed for user root Apr 27 19:26:04 rivo volumio[4345]: error: Cannot start Volumio Streaming Daemon Apr 27 19:26:04 rivo volumio[4345]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 27 19:26:04 rivo volumio[4345]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 27 19:26:04 rivo volumio[4345]: info: Setting Geolocation for MyVolumio to eu4 Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:04 rivo volumio[4345]: error: Failed request for metavolumio API Apr 27 19:26:04 rivo volumio[4345]: error: Failed request for metavolumio API Apr 27 19:26:05 rivo volumio[4345]: info: Updating MyVolumio device info Apr 27 19:26:05 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:05 rivo volumio[4345]: info: Successfully Added MyVolumio device Apr 27 19:26:05 rivo volumio[4345]: info: Successfully Added MyVolumio device Apr 27 19:26:05 rivo volumio[4345]: info: Updating MyVolumio device info Apr 27 19:26:05 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:05 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:05 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:05 rivo volumio[4345]: info: Successfully Updated MyVolumio device Apr 27 19:26:05 rivo volumio[4345]: info: Successfully Updated MyVolumio device Apr 27 19:26:08 rivo sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 27 19:26:08 rivo sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 19:26:08 rivo autossh[4263]: received signal to exit (15) Apr 27 19:26:08 rivo systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 27 19:26:08 rivo systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 27 19:26:08 rivo systemd[1]: sshtunnel.service: Succeeded. Apr 27 19:26:08 rivo systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 27 19:26:08 rivo volumio[4345]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 27 19:26:08 rivo systemd[1]: Started MyVolumio SSH Tunnel. Apr 27 19:26:08 rivo sudo[5068]: pam_unix(sudo:session): session closed for user root Apr 27 19:26:08 rivo volumio[4345]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 27 19:26:08 rivo volumio[4345]: info: Remote SSH Started Apr 27 19:26:08 rivo autossh[5086]: port set to 0, monitoring disabled Apr 27 19:26:08 rivo autossh[5086]: starting ssh (count 1) Apr 27 19:26:08 rivo autossh[5086]: ssh child pid is 5100 Apr 27 19:26:08 rivo volumio[4345]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 34 Apr 27 19:26:08 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:26:08 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:26:08 rivo volumio[4345]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.HChUTMmKbaa2hksy3Jz8ffWfeeU2.ad6af1e6d35ff082aaa83c88a01d1480.state.mute' Apr 27 19:26:08 rivo volumiossh-tunnel[5070]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Apr 27 19:26:12 rivo volumio[4345]: error: MyVolumio Plugin failed to start in a timely fashion Apr 27 19:26:12 rivo volumio[4345]: info: BOOT COMPLETED Apr 27 19:26:12 rivo volumio[4345]: [Metrics] CommandRouter: 46s 726.47ms Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::volumiosetStartupVolume Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:26:12 rivo volumio[4345]: info: VolumeController:: Setting startup Volume 25 Apr 27 19:26:12 rivo volumio[4345]: info: VolumeController::SetAlsaVolume25 Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::Close All Modals sent Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::Close All Modals sent Apr 27 19:26:12 rivo volumio[4345]: info: CoreStateMachine::pushState Apr 27 19:26:12 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 19:26:12 rivo volumio[4345]: info: CoreCommandRouter::volumioPushState Apr 27 19:26:12 rivo volumio[4345]: info: MRS: Pushing multiroomSync output update for this device Apr 27 19:26:12 rivo volumio[4345]: info: MRS: Pushing multiroomSync output Apr 27 19:26:12 rivo volumio[4345]: info: Updating RAAT Signal Path Apr 27 19:26:12 rivo volumio[4345]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 27 19:26:12 rivo volumio[4345]: xcb_connection_has_error() returned true Apr 27 19:26:13 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 27 19:26:13 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 19:26:13 rivo volumio[4345]: No protocol specified Apr 27 19:26:13 rivo volumio[4345]: xcb_connection_has_error() returned true Apr 27 19:26:13 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 27 19:26:13 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 27 19:26:13 rivo volumio[4345]: info: CURURI: playlists/Tchaikovsky Swan Lake Boston Simphony Apr 27 19:26:13 rivo volumio[4345]: info: Preload queue cleared Apr 27 19:26:13 rivo volumio[4345]: info: Preloading song: qobuz://song/48326083 Apr 27 19:26:13 rivo volumio[4345]: info: Exploding uri qobuz://song/48326083 in service qobuz Apr 27 19:26:14 rivo volumio[4345]: info: explodeUri took 433 milliseconds Apr 27 19:26:15 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 19:26:15 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 19:26:15 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 19:26:16 rivo volumio-remote-updater[4732]: No test mode Apr 27 19:26:16 rivo volumio-remote-updater[4732]: No alpha test mode Apr 27 19:26:16 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 27 19:26:16 rivo volumio[4345]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 27 19:26:16 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 27 19:26:16 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 19:26:16 rivo volumio[4345]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 35 Apr 27 19:26:16 rivo volumio[4345]: info: CoreCommandRouter::volumioGetState Apr 27 19:26:16 rivo volumio[4345]: info: CorePlayQueue::getTrack 0 Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 19:26:21 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 19:26:22 rivo volumio[4345]: info: Deleting playlist Tchaikovsky Conc.Violino Karajan Mutter Apr 27 19:26:22 rivo volumio[4345]: info: Deleting Cloud item Tchaikovsky Conc.Violino Karajan Mutter Apr 27 19:26:22 rivo volumio[4345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , deleteCloudPlaylist Apr 27 19:26:22 rivo volumio[4345]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 19:26:22 rivo volumio[4345]: Error: Reference.child failed: First argument was an invalid path = "/user_data/HChUTMmKbaa2hksy3Jz8ffWfeeU2/myPlaylists/Tchaikovsky Conc.Violino Karajan Mutter". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Apr 27 19:26:22 rivo volumio[4345]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Apr 27 19:26:22 rivo volumio[4345]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Apr 27 19:26:22 rivo volumio[4345]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Apr 27 19:26:22 rivo volumio[4345]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Apr 27 19:26:22 rivo volumio[4345]: at myVolumio.deleteCloudPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:24386) Apr 27 19:26:22 rivo volumio[4345]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Apr 27 19:26:22 rivo volumio[4345]: at PlaylistManager.deleteJSONFile (/volumio/app/playlistManager.js:634:35) Apr 27 19:26:22 rivo volumio[4345]: at /volumio/app/playlistManager.js:58:19 Apr 27 19:26:22 rivo volumio[4345]: at suppressedCallback (fs.js:236:5) Apr 27 19:26:22 rivo volumio[4345]: at FSReqCallback.oncomplete (fs.js:180:23) Apr 27 19:26:22 rivo volumio[4345]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 19:26:22 rivo sudo[5142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 19:25 Apr 27 19:26:22 rivo sudo[5142]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:41:55 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b0437c7088d6645a661a49f6d9fa596a"