Dec 24 20:48:01 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 83. Dec 24 20:48:01 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:01 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:01 volumio-4 upmpdcli[3999]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:48:01 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:48:01 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:48:13 volumio-4 vtcs[1621]: [close:85] Entering Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:13 volumio-4 vtcs[1621]: [feedThread:276] Exiting Dec 24 20:48:13 volumio-4 vtcs[1621]: [close:100] Exiting Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:13 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: Pushing metadata Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:14 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: Pushing metadata Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 vtcs[1621]: [close:85] Entering Dec 24 20:48:15 volumio-4 vtcs[1621]: [close:100] Exiting Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 vtcs[1621]: [open:50] Opening with sample rate: 44100, type: 0 Dec 24 20:48:15 volumio-4 vtcs[1621]: [start:106] Entering Dec 24 20:48:15 volumio-4 vtcs[1621]: [start:113] Reopening PCM device... Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:15 volumio-4 vtcs[1621]: [start:115] PCM opened successfully. Dec 24 20:48:15 volumio-4 vtcs[1621]: [start:120] Exiting Dec 24 20:48:15 volumio-4 vtcs[1621]: [feedThread:223] Entering Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:15 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:16 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 84. Dec 24 20:48:16 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:16 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:16 volumio-4 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 24 20:48:16 volumio-4 volumio[1156]: info: CoreStateMachine::pushState Dec 24 20:48:16 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:48:16 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 24 20:48:16 volumio-4 upmpdcli[4036]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:48:16 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:48:16 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:48:18 volumio-4 volumio[1156]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Dec 24 20:48:22 volumio-4 volumio[1156]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Dec 24 20:48:22 volumio-4 volumio[1156]: info: Folder /tmp/plugins removed Dec 24 20:48:23 volumio-4 volumio[1156]: info: Check plugin dependencies Dec 24 20:48:23 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 20:48:23 volumio-4 volumio[1156]: info: Checking if plugin already exists Dec 24 20:48:23 volumio-4 volumio[1156]: info: Rename folder Dec 24 20:48:23 volumio-4 volumio[1156]: info: Folder /tmp/downloaded_plugin.zip removed Dec 24 20:48:23 volumio-4 volumio[1156]: info: Move to category Dec 24 20:48:26 volumio-4 volumio[1156]: info: Checking if install.sh is present Dec 24 20:48:26 volumio-4 volumio[1156]: info: Executing install.sh Dec 24 20:48:26 volumio-4 sudo[4071]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/install.sh Dec 24 20:48:26 volumio-4 sudo[4071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:48:26 volumio-4 sudo[4071]: pam_unix(sudo:session): session closed for user root Dec 24 20:48:26 volumio-4 volumio[1156]: info: Install script completed Dec 24 20:48:26 volumio-4 volumio[1156]: info: Adding reference to registry Dec 24 20:48:26 volumio-4 volumio[1156]: info: Done installing plugin. Dec 24 20:48:26 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 20:48:26 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 24 20:48:26 volumio-4 volumio[1156]: info: Skipping Deno installation - not supported on armhf Dec 24 20:48:26 volumio-4 volumio[1156]: info: YouTube2 plugin installed Dec 24 20:48:26 volumio-4 volumio[1156]: Plugin install end detected on script Dec 24 20:48:26 volumio-4 volumio[1156]: info: Folder /tmp/plugins removed Dec 24 20:48:26 volumio-4 volumio[1156]: info: Folder /tmp/downloaded_plugin.zip removed Dec 24 20:48:26 volumio-4 volumio[1156]: info: Folder /data/temp removed Dec 24 20:48:27 volumio-4 volumio[1156]: info: Enabling plugin youtube2 Dec 24 20:48:27 volumio-4 volumio[1156]: info: Loading plugin "youtube2"... Dec 24 20:48:28 volumio-4 volumio[1156]: info: PLUGIN START: youtube2 Dec 24 20:48:28 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 24 20:48:28 volumio-4 volumio[1156]: info: [1766605708272] CoreMusicLibrary::Adding element YouTube2 Dec 24 20:48:28 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 20:48:28 volumio-4 volumio[1156]: Cannot find translation for source TIDAL Dec 24 20:48:28 volumio-4 volumio[1156]: Cannot find translation for source YouTube2 Dec 24 20:48:28 volumio-4 volumio[1156]: info: Done. Dec 24 20:48:31 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 85. Dec 24 20:48:31 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:31 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:31 volumio-4 upmpdcli[4076]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:48:31 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:48:31 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 20:48:35 volumio-4 volumio[1156]: info: Getting Alsa Cards List without I2S DAC Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 20:48:35 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: info: Retrieving Cloud Streaming UI Dec 24 20:48:39 volumio-4 volumio[1156]: info: Getting Tidal Cloud Configuration Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: info: Getting Qobuz Cloud Configuration Dec 24 20:48:39 volumio-4 volumio[1156]: info: Asking plugin for UI Config Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: info: Getting Spotify Cloud Configuration Dec 24 20:48:39 volumio-4 volumio[1156]: info: Asking plugin for UI Config Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: info: Saving Spotify Acccount Dec 24 20:48:39 volumio-4 volumio[1156]: info: Got it Dec 24 20:48:39 volumio-4 volumio[1156]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 24 20:48:39 volumio-4 volumio[1156]: info: Got Tidal Cloud Configuration Dec 24 20:48:39 volumio-4 volumio[1156]: info: Got it Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:48:39 volumio-4 volumio[1156]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioGetBrowseSources Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:48:39 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 24 20:48:43 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:48:46 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 86. Dec 24 20:48:46 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:46 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:48:46 volumio-4 upmpdcli[4125]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:48:46 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:48:46 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:48:47 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:48:51 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:48:56 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:00 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:02 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 87. Dec 24 20:49:02 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:49:02 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:49:02 volumio-4 upmpdcli[4141]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:49:02 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:49:02 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:49:04 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:08 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:12 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:16 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:17 volumio-4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 88. Dec 24 20:49:17 volumio-4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:49:17 volumio-4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 24 20:49:17 volumio-4 upmpdcli[4173]: Could not open config: /tmp/upmpdcli.conf Dec 24 20:49:17 volumio-4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 20:49:17 volumio-4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 20:49:20 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:23 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:27 volumio-4 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 24 20:49:28 volumio-4 volumio[1156]: info: Enabling MyMusic plugin bluetooth Dec 24 20:49:28 volumio-4 volumio[1156]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Dec 24 20:49:28 volumio-4 volumio[1156]: info: Enabling plugin bluetooth Dec 24 20:49:28 volumio-4 volumio[1156]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 24 20:49:28 volumio-4 bluetoothd[788]: Path / reserved for Adv Monitor app :1.16 Dec 24 20:49:28 volumio-4 bluetoothd[788]: Adv Monitor app :1.16 disconnected from D-Bus Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 24 20:49:28 volumio-4 sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 24 20:49:28 volumio-4 sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:49:28 volumio-4 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 24 20:49:28 volumio-4 sudo[4195]: pam_unix(sudo:session): session closed for user root Dec 24 20:49:28 volumio-4 volumio[1156]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 24 20:49:28 volumio-4 volumio[1156]: info: CoreCommandRouter::volumioStop Dec 24 20:49:28 volumio-4 volumio[1156]: info: CoreStateMachine::stop Dec 24 20:49:28 volumio-4 volumio[1156]: info: CoreStateMachine::serviceStop Dec 24 20:49:28 volumio-4 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 24 20:49:28 volumio-4 vtcs[1621]: [close:85] Entering Dec 24 20:49:28 volumio-4 volumio[1156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:49:28 volumio-4 volumio[1156]: TypeError: Cannot read properties of undefined (reading 'then') Dec 24 20:49:28 volumio-4 volumio[1156]: at /myvolumio/plugins/audio_interface/bluetooth/bluetooth_real:1:12468 Dec 24 20:49:28 volumio-4 volumio[1156]: at ChildProcess.exithandler (node:child_process:413:7) Dec 24 20:49:28 volumio-4 volumio[1156]: at ChildProcess.emit (node:events:514:28) Dec 24 20:49:28 volumio-4 volumio[1156]: at maybeClose (node:internal/child_process:1105:16) Dec 24 20:49:28 volumio-4 volumio[1156]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 24 20:49:28 volumio-4 volumio[1156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:49:28 volumio-4 volumiobt[4198]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 24 20:49:28 volumio-4 vtcs[1621]: [feedThread:276] Exiting Dec 24 20:49:28 volumio-4 vtcs[1621]: [close:100] Exiting Dec 24 20:49:28 volumio-4 sudo[4200]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 24 20:49:28 volumio-4 sudo[4200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:49:28 volumio-4 sudo[4200]: pam_unix(sudo:session): session closed for user root Dec 24 20:49:28 volumio-4 sudo[4205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 24 20:49:28 volumio-4 sudo[4205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:49:28 volumio-4 sudo[4205]: pam_unix(sudo:session): session closed for user root Dec 24 20:49:28 volumio-4 volumiobt[4215]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 24 20:49:28 volumio-4 bluetoothd[788]: Adv Monitor app :1.17 disconnected from D-Bus Dec 24 20:49:28 volumio-4 volumiobt[4219]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 24 20:49:28 volumio-4 volumiobt[4220]: [176B blob data] Dec 24 20:49:28 volumio-4 volumiobt[4220]: [157B blob data] Dec 24 20:49:28 volumio-4 volumiobt[4220]: [157B blob data] Dec 24 20:49:28 volumio-4 volumiobt[4220]: [157B blob data] Dec 24 20:49:28 volumio-4 volumiobt[4220]: [113B blob data] Dec 24 20:49:28 volumio-4 volumiobt[4220]: [bluetoothctl]> discoverable on Dec 24 20:49:28 volumio-4 volumiobt[4220]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 24 20:49:28 volumio-4 volumiobt[4220]: [bluetoothctl]> pairable on Dec 24 20:49:28 volumio-4 bluetoothd[788]: Path / reserved for Adv Monitor app :1.18 Dec 24 20:49:28 volumio-4 bluetoothd[788]: Adv Monitor app :1.18 disconnected from D-Bus Dec 24 20:49:28 volumio-4 volumiobt[4220]: [bluetoothctl]> Dec 24 20:49:28 volumio-4 volumiobt[4221]: INFO [BTSTART] Registering Bluetooth agent... Dec 24 20:49:28 volumio-4 volumiobt[4222]: [NEW] Media /org/bluez/hci0 Dec 24 20:49:28 volumio-4 volumiobt[4222]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 volumiobt[4222]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 volumiobt[4222]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 bluetoothd[788]: Adv Monitor app :1.19 disconnected from D-Bus Dec 24 20:49:28 volumio-4 volumiobt[4223]: No agent is registered Dec 24 20:49:28 volumio-4 volumiobt[4223]: [NEW] Media /org/bluez/hci0 Dec 24 20:49:28 volumio-4 volumiobt[4223]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 volumiobt[4223]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 volumiobt[4223]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 24 20:49:28 volumio-4 bluetoothd[788]: Adv Monitor app :1.20 disconnected from D-Bus Dec 24 20:49:28 volumio-4 volumiobt[4224]: INFO [BTSTART] Agent registered successfully. Dec 24 20:49:28 volumio-4 volumiobt[4225]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 24 20:49:28 volumio-4 sudo[4229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-24 20:48' Dec 24 20:49:28 volumio-4 sudo[4229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"