-- Logs begin at Wed 2024-10-09 01:15:19 JST, end at Wed 2024-10-09 01:19:58 JST. -- Oct 09 01:18:04 volumio volumio[922]: Uploading: bluetooth_controller.zip Oct 09 01:18:04 volumio volumio[922]: Created safe filename as '92f4c3ec-1442-45c7-95d9-76538ca616b5.zip' Oct 09 01:18:04 volumio volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 09 01:18:04 volumio volumio[922]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/92f4c3ec-1442-45c7-95d9-76538ca616b5.zip Oct 09 01:18:04 volumio volumio[922]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/92f4c3ec-1442-45c7-95d9-76538ca616b5.zip Oct 09 01:18:04 volumio volumio[922]: info: Folder /tmp/plugins removed Oct 09 01:18:04 volumio volumio[922]: info: Check plugin dependencies Oct 09 01:18:04 volumio volumio[922]: info: Checking if plugin already exists Oct 09 01:18:04 volumio volumio[922]: info: An error occurred installing the plugin. Rolling back config Oct 09 01:18:04 volumio volumio[922]: info: Plugin folders cleanup Oct 09 01:18:04 volumio volumio[922]: info: Scanning into folder /volumio/app/plugins/ Oct 09 01:18:04 volumio volumio[922]: info: Scanning category audio_interface Oct 09 01:18:04 volumio volumio[922]: info: Scanning category miscellanea Oct 09 01:18:04 volumio volumio[922]: info: Scanning category music_service Oct 09 01:18:04 volumio volumio[922]: info: Scanning category plugins.json Oct 09 01:18:04 volumio volumio[922]: info: Scanning category system_controller Oct 09 01:18:04 volumio volumio[922]: info: Scanning category user_interface Oct 09 01:18:04 volumio volumio[922]: info: Scanning into folder /data/plugins/ Oct 09 01:18:04 volumio volumio[922]: info: Scanning category audio_interface Oct 09 01:18:04 volumio volumio[922]: info: Scanning category music_service Oct 09 01:18:04 volumio volumio[922]: info: Plugin folders cleanup completed Oct 09 01:18:04 volumio volumio[922]: info: Error: Error Oct 09 01:18:04 volumio volumio[922]: info: Folder /tmp/plugins removed Oct 09 01:18:04 volumio volumio[922]: info: Folder /tmp/downloaded_plugin.zip removed Oct 09 01:18:04 volumio volumio[922]: info: Folder /tmp/downloaded_plugin.zip removed Oct 09 01:18:04 volumio volumio[922]: info: Folder /data/temp removed Oct 09 01:18:30 volumio volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 01:18:30 volumio volumio[922]: info: Discoverable: false Oct 09 01:18:30 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:30 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:30 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:30 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:30 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:30 volumio volumio[922]: "payload": { Oct 09 01:18:30 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:30 volumio volumio[922]: "devices": [] Oct 09 01:18:30 volumio volumio[922]: } Oct 09 01:18:30 volumio volumio[922]: } Oct 09 01:18:33 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller saveOptions [object Object] Oct 09 01:18:33 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , saveOptions Oct 09 01:18:33 volumio volumio[922]: info: Set bluetooth disvoverable to true Oct 09 01:18:33 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:36 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:36 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:36 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:36 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:36 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:36 volumio volumio[922]: "payload": { Oct 09 01:18:36 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:36 volumio volumio[922]: "devices": [] Oct 09 01:18:36 volumio volumio[922]: } Oct 09 01:18:36 volumio volumio[922]: } Oct 09 01:18:37 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:37 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:37 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:37 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:37 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:37 volumio volumio[922]: "payload": { Oct 09 01:18:37 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:37 volumio volumio[922]: "devices": [] Oct 09 01:18:37 volumio volumio[922]: } Oct 09 01:18:37 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:40 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:40 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:40 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:40 volumio volumio[922]: "payload": { Oct 09 01:18:40 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:40 volumio volumio[922]: "devices": [] Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:40 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:40 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:40 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:40 volumio volumio[922]: "payload": { Oct 09 01:18:40 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:40 volumio volumio[922]: "devices": [] Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:40 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:40 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:40 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:40 volumio volumio[922]: "payload": { Oct 09 01:18:40 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:40 volumio volumio[922]: "devices": [] Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:40 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:41 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:41 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:41 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:41 volumio volumio[922]: "payload": { Oct 09 01:18:41 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:41 volumio volumio[922]: "devices": [] Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:41 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:41 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:41 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:41 volumio volumio[922]: "payload": { Oct 09 01:18:41 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:41 volumio volumio[922]: "devices": [] Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:41 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:41 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:41 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:41 volumio volumio[922]: "payload": { Oct 09 01:18:41 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:41 volumio volumio[922]: "devices": [] Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:41 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:41 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:41 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:41 volumio volumio[922]: "payload": { Oct 09 01:18:41 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:41 volumio volumio[922]: "devices": [] Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:41 volumio volumio[922]: } Oct 09 01:18:50 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:53 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:53 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:53 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:53 volumio volumio[922]: "payload": { Oct 09 01:18:53 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:53 volumio volumio[922]: "devices": [] Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:53 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:53 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:53 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:53 volumio volumio[922]: "payload": { Oct 09 01:18:53 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:53 volumio volumio[922]: "devices": [] Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:53 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:53 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:53 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:53 volumio volumio[922]: "payload": { Oct 09 01:18:53 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:53 volumio volumio[922]: "devices": [] Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:53 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:53 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:53 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:53 volumio volumio[922]: "payload": { Oct 09 01:18:53 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:53 volumio volumio[922]: "devices": [] Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:53 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:53 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:53 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:53 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:53 volumio volumio[922]: "payload": { Oct 09 01:18:53 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:53 volumio volumio[922]: "devices": [] Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:53 volumio volumio[922]: } Oct 09 01:18:54 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Oct 09 01:18:54 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Oct 09 01:18:54 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:18:54 volumio volumio[922]: info: Found bluetooth devices: { Oct 09 01:18:54 volumio volumio[922]: "message": "pushBluetoothDevices", Oct 09 01:18:54 volumio volumio[922]: "payload": { Oct 09 01:18:54 volumio volumio[922]: "hasBluetooth": true, Oct 09 01:18:54 volumio volumio[922]: "devices": [] Oct 09 01:18:54 volumio volumio[922]: } Oct 09 01:18:54 volumio volumio[922]: } Oct 09 01:18:56 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:18:57 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:00 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:00 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:00 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:01 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:01 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:01 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:01 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:13 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:13 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:13 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:13 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:13 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:14 volumio volumio[922]: info: Stopping bluetooth device scan Oct 09 01:19:45 volumio volumio[922]: info: CALLMETHOD: audio_interface bluetooth_controller saveOptions [object Object] Oct 09 01:19:45 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , saveOptions Oct 09 01:19:45 volumio volumio[922]: info: Set bluetooth disvoverable to false Oct 09 01:19:45 volumio volumio[922]: info: Starting bluetooth device scan Oct 09 01:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 01:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 09 01:19:53 volumio volumio[922]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Oct 09 01:19:53 volumio volumio[922]: info: Uninstalling plugin bluetooth_controller Oct 09 01:19:55 volumio volumio[922]: info: Enabling plugin bluetooth_controller Oct 09 01:19:55 volumio volumio[922]: info: Loading plugin "bluetooth_controller"... Oct 09 01:19:58 volumio volumio[922]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Oct 09 01:19:58 volumio volumio[922]: info: Uninstalling plugin bluetooth_controller Oct 09 01:19:58 volumio volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 01:19:58 volumio volumio[922]: TypeError: Cannot read property 'close' of undefined Oct 09 01:19:58 volumio volumio[922]: at BluetoothController.onStop (/data/plugins/audio_interface/bluetooth_controller/index.js:137:21) Oct 09 01:19:58 volumio volumio[922]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 09 01:19:58 volumio volumio[922]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 09 01:19:58 volumio volumio[922]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Oct 09 01:19:58 volumio volumio[922]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Oct 09 01:19:58 volumio volumio[922]: at Socket.emit (events.js:315:20) Oct 09 01:19:58 volumio volumio[922]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 09 01:19:58 volumio volumio[922]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 09 01:19:58 volumio volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 01:19:58 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 01:18 Oct 09 01:19:58 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"