home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
73.43k stars 30.67k forks source link

Yale Access Bluetooth - August Bluetooth not connecting to Bluetooth Proxy - BleakOutOfConnectionSlotsError #81468

Closed austwhite closed 1 year ago

austwhite commented 2 years ago

The problem

August Smartlock is snot connecting to ESPHome Bluetooth Proxy. The lock will work fine when close to a standard USB Bluetooth dongle, but will not connect to ESPHome BT Proxy. Even if the lock is the only device on bluetooth, it still will not connect. First error will give an unable to connect, then subsequent error will give no available slot.

What version of Home Assistant Core has the issue?

2022.11.0

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant OS

Integration causing the issue

Yale Access Bluetooth

Link to integration documentation on our website

https://www.home-assistant.io/integrations/yalexs_ble/

Diagnostics information

Logger: yalexs_ble.push Source: /usr/local/lib/python3.10/site-packages/yalexs_ble/push.py:604 First occurred: 9:57:46 PM (1 occurrences) Last logged: 9:57:46 PM

FRONT DOOR (78:9C:85:01:CB:3D): Error updating Traceback (most recent call last): File "/usr/local/lib/python3.10/site-packages/bleak_retry_connector/init.py", line 543, in establish_connection await client.connect( File "/usr/src/homeassistant/homeassistant/components/bluetooth/models.py", line 294, in connect self._async_get_backend() or self._async_get_fallback_backend() File "/usr/src/homeassistant/homeassistant/components/bluetooth/models.py", line 370, in _async_get_fallback_backend raise BleakError( bleak.exc.BleakError: No backend with an available connection slot that can reach address 78:9C:85:01:CB:3D was found

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.10/site-packages/yalexs_ble/push.py", line 573, in _queue_update await self._update_task File "/usr/local/lib/python3.10/site-packages/yalexs_ble/push.py", line 87, in _async_wrap_operation_lock return await func(self, *args, *kwargs) File "/usr/local/lib/python3.10/site-packages/yalexs_ble/push.py", line 108, in _async_wrap_retry_bluetooth_connection_error return await func(self, args, **kwargs) File "/usr/local/lib/python3.10/site-packages/yalexs_ble/push.py", line 384, in _update async with lock: File "/usr/local/lib/python3.10/site-packages/yalexs_ble/lock.py", line 61, in aenter await self.connect() File "/usr/local/lib/python3.10/site-packages/yalexs_ble/lock.py", line 90, in connect raise err File "/usr/local/lib/python3.10/site-packages/yalexs_ble/lock.py", line 80, in connect self.client = await establish_connection( File "/usr/local/lib/python3.10/site-packages/bleak_retry_connector/init.py", line 616, in establish_connection _raise_if_needed(name, description, exc) File "/usr/local/lib/python3.10/site-packages/bleak_retry_connector/init.py", line 487, in _raise_if_needed raise BleakOutOfConnectionSlotsError( bleak_retry_connector.BleakOutOfConnectionSlotsError: FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchen-ble-proxy: Failed to connect: No backend with an available connection slot that can reach address 78:9C:85:01:CB:3D was found: The proxy/adapter is out of connection slots; Add additional proxies near this device

Logger: yalexs_ble.lock Source: runner.py:119 First occurred: 9:55:35 PM (19 occurrences) Last logged: 10:00:52 PM

FRONT DOOR (AugSW38): Failed to connect to the lock: FRONT DOOR (AugSW38) - 78:9C:85:01:CB:3D -> kitchen-ble-proxy: Failed to connect: No backend with an available connection slot that can reach address 78:9C:85:01:CB:3D was found: The proxy/adapter is out of connection slots; Add additional proxies near this device FRONT DOOR (78:9C:85:01:CB:3D): Failed to connect to the lock: FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchen-ble-proxy: Failed to connect: No backend with an available connection slot that can reach address 78:9C:85:01:CB:3D was found: The proxy/adapter is out of connection slots; Add additional proxies near this device FRONT DOOR (78:9C:85:01:CB:3D): Failed to connect to the lock: FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchen-ble-proxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish: Interference/range; External Bluetooth adapter w/extension may help; Extension cables reduce USB 3 port interference

Example YAML snippet

No YAML

Anything in the logs that might be useful for us?

No response

Additional information

The integration works fine for locks within range of a standard USB Bluetooth Dongle, but does not work with ESP Home proxy

home-assistant[bot] commented 2 years ago

Hey there @bdraco, mind taking a look at this issue as it has been labeled with an integration (yalexs_ble) you are listed as a code owner for? Thanks!

Code owner commands Code owners of `yalexs_ble` can trigger bot actions by commenting: - `@home-assistant close` Closes the issue. - `@home-assistant rename Awesome new title` Change the title of the issue. - `@home-assistant reopen` Reopen the issue. - `@home-assistant unassign yalexs_ble` Removes the current integration label and assignees on the issue, add the integration domain after the command.

(message by CodeOwnersMention)


yalexs_ble documentation yalexs_ble source (message by IssueLinks)

bdraco commented 2 years ago

If you enable debug logs for homeassistant.components.bluetooth before you connect we can see when the slot is being allocated

austwhite commented 2 years ago

Will do that now. It also does not connect via HomeKit on the ESPHome BT Proxy/ Likelylikely same thing?
I'll sort out the debug logs for you.

bdraco commented 2 years ago

It also does not connect via HomeKit on the ESPHome BT Proxy/ Likelylikely same thing?

Let's work on one issue at a time otherwise we risk solving one problem and not solving the other issue.

austwhite commented 2 years ago

I set to "debug" for logging and it gives this error

Logger: yalexs_ble.lock Source: runner.py:119 First occurred: 11:08:56 PM (1 occurrences) Last logged: 11:08:56 PM

FRONT DOOR (78:9C:85:01:CB:3D): Failed to connect to the lock: FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchen-ble-proxy: Failed to connect: No backend with an available connection slot that can reach address 78:9C:85:01:CB:3D was found: The proxy/adapter is out of connection slots; Add additional proxies near this device

austwhite commented 2 years ago

Note: There are no other BLE devices currently. The lock is the only device trying to connect to the ESP32

bdraco commented 2 years ago

Please post the full debug logs for esphome. The lines before that will be more helpful.

austwhite commented 2 years ago

Can I clarify which logs you want to see? Are they the ones logged from Home Assistant Core?

bdraco commented 2 years ago

All the lines in the log that have homeassistant.components.esphome

grep homeassistant.components.esphome /config/home-assistant.log

austwhite commented 2 years ago

I will apologise as I am just reading the logs from the Home Assistant GUI, not really a linux person here.... I hope maybe this has something helpful in it.

2022-11-03 23:44:34.488 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Connecting scanner version=2, connectable=True 2022-11-03 23:44:34.671 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:44:34.753 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 09:DC:7D:2D:A6:D2 AdvertisementData(manufacturer_data={6: b'\x01\t "\x02\xb1\xf2g\x14\x06#\x1bMo\xd8\xd8g\xabqq\xd6A\xeeqM\x88\xbc'}, tx_power=-127, rssi=-67) connectable: True match: set() rssi: -67 2022-11-03 23:44:34.888 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 80:7A:BF:E9:E4:21 AdvertisementData(local_name='HTC BS 216A96', service_uuids=['0000cb00-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-77) connectable: True match: set() rssi: -77 2022-11-03 23:44:34.888 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: F9:C8:D8:80:3C:96 AdvertisementData(local_name='S', manufacturer_data={880: b'\x05\xe4\x00\x00Kitchen0 '}, tx_power=-127, rssi=-86) connectable: True match: set() rssi: -86 2022-11-03 23:44:35.063 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 65:CF:48:96:BC:29 AdvertisementData(manufacturer_data={224: b'\x00"\xca\x85\x1a\xde'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-79) connectable: True match: set() rssi: -79 2022-11-03 23:44:35.264 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:65:E7:93:81:9B AdvertisementData(local_name='S', manufacturer_data={880: b'\x05\xe2\x00\x00Living room0 '}, tx_power=-127, rssi=-81) connectable: True match: set() rssi: -81 2022-11-03 23:44:35.264 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: FC:4F:A2:51:AD:E2 AdvertisementData(local_name='Bathroom globe 2', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'\x1b\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-70) connectable: True match: set() rssi: -70 2022-11-03 23:44:35.387 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 28:B1:FE:1F:83:F5 AdvertisementData(manufacturer_data={6: b'\x01\t &\xe1^\x1a \rd\x08\x81\x8b\x96\x1f\r\xae\xd1v\x98\xa65\xca\xc1m\x9e\x8a'}, tx_power=-127, rssi=-77) connectable: True match: set() rssi: -77 2022-11-03 23:44:35.388 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 73:98:4D:4C:75:B3 AdvertisementData(manufacturer_data={224: b'\x14\x02\xca\x92n\xad'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:44:35.388 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 5D:91:D7:CA:AE:75 AdvertisementData(manufacturer_data={224: b'\x00 \xca8\xda\xac'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-03 23:44:35.409 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 51:A7:20:12:E7:5A AdvertisementData(local_name='Kitchen display', service_data={'0000fea0-0000-1000-8000-00805f9b34fb': b'\x03\xfa\x8f\xcad\xa0}w \x9f\xff[\x9a'}, service_uuids=['0000fea0-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-61) connectable: True match: set() rssi: -61 2022-11-03 23:44:36.046 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 80:7A:BF:E9:E2:5A AdvertisementData(local_name='HTC BS 5A624B', service_uuids=['0000cb00-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-80) connectable: True match: set() rssi: -80 2022-11-03 23:44:36.047 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: F5:FF:FA:CC:57:1F AdvertisementData(local_name='Bathroom globe 1', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'\x1b\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-79) connectable: True match: set() rssi: -79 2022-11-03 23:44:36.047 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: FB:69:00:31:AC:56 AdvertisementData(local_name='Bathroom fan', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'Z\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:44:36.281 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00w\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xc9\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: {'xiaomi_ble'} rssi: -92 2022-11-03 23:44:36.401 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 174803132): LightState(key=174803132, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:44:36.425 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 2472414331): LightState(key=2472414331, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:44:36.427 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 3184812465): LightState(key=3184812465, state=False, brightness=1.0, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:44:36.442 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 3184812470): LightState(key=3184812470, state=False, brightness=1.0, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:44:36.473 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=31.375, missing_state=False) 2022-11-03 23:44:36.528 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SwitchState'>, 320112106): SwitchState(key=320112106, state=False) 2022-11-03 23:44:36.679 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\x13\x07\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-73) connectable: True match: set() rssi: -73 2022-11-03 23:44:37.181 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:44:37.220 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:44:37.721 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 59:26:D9:BE:F1:86 AdvertisementData(manufacturer_data={224: b"\x00!\xcalX'"}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-78) connectable: True match: set() rssi: -78 2022-11-03 23:44:38.168 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 5B:3F:2B:8C:15:56 AdvertisementData(manufacturer_data={6: b'\x01\t \x02.5\x08Ds!_j\xa4,r\xa9e\\x94!,8|\x85d\xaf\xf5'}, tx_power=-127, rssi=-99) connectable: True match: set() rssi: -99 2022-11-03 23:44:38.952 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:44:38.956 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:44:39.504 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:44:39.599 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:44:39.873 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00!\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-85) connectable: True match: {'xiaomi_ble'} rssi: -85 2022-11-03 23:44:39.974 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 7D:A0:2E:C1:CA:85 AdvertisementData(manufacturer_data={224: b'\x00)\xca\xebo`'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:44:42.225 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00x\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-03 23:44:43.875 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:44:43.876 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:44:43.879 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:44:44.427 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:44:44.480 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:44:44.891 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 44:C6:5D:82:DD:3B AdvertisementData(manufacturer_data={76: b'\x10\x05\x01\x14}\xb2\xef'}, tx_power=-127, rssi=-90) connectable: True match: set() rssi: -90 2022-11-03 23:44:45.085 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 72:32:78:87:1A:1F AdvertisementData(manufacturer_data={76: b'\x10\x05\x11\x1cK\x9b\xac'}, tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-03 23:44:51.029 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:44:51.029 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:44:51.029 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-03 23:44:51.030 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services miss 2022-11-03 23:44:51.036 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:44:51.355 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Got services: ESPHomeBluetoothGATTServices(address=132613641718589, services=[BluetoothGATTService(uuid='00001800-0000-1000-8000-00805f9b34fb', handle=20, characteristics=[BluetoothGATTCharacteristic(uuid='00002a00-0000-1000-8000-00805f9b34fb', handle=22, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a01-0000-1000-8000-00805f9b34fb', handle=24, properties=2, descriptors=[])]), BluetoothGATTService(uuid='0000003e-0000-1000-8000-0026bb765291', handle=40, characteristics=[BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=42, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000014-0000-1000-8000-0026bb765291', handle=44, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=45)]), BluetoothGATTCharacteristic(uuid='00000020-0000-1000-8000-0026bb765291', handle=47, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=48)]), BluetoothGATTCharacteristic(uuid='00000021-0000-1000-8000-0026bb765291', handle=50, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=51)]), BluetoothGATTCharacteristic(uuid='00000023-0000-1000-8000-0026bb765291', handle=53, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=54)]), BluetoothGATTCharacteristic(uuid='00000030-0000-1000-8000-0026bb765291', handle=56, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=57)]), BluetoothGATTCharacteristic(uuid='00000052-0000-1000-8000-0026bb765291', handle=59, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=60)]), BluetoothGATTCharacteristic(uuid='000000a6-0000-1000-8000-0026bb765291', handle=62, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=63)])]), BluetoothGATTService(uuid='0000180a-0000-1000-8000-00805f9b34fb', handle=64, characteristics=[BluetoothGATTCharacteristic(uuid='00002a24-0000-1000-8000-00805f9b34fb', handle=66, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a25-0000-1000-8000-00805f9b34fb', handle=68, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a26-0000-1000-8000-00805f9b34fb', handle=70, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a29-0000-1000-8000-00805f9b34fb', handle=76, properties=2, descriptors=[])]), BluetoothGATTService(uuid='000000a2-0000-1000-8000-0026bb765291', handle=80, characteristics=[BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=82, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000037-0000-1000-8000-0026bb765291', handle=84, properties=42, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=85), BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=86)])]), BluetoothGATTService(uuid='00000044-0000-1000-8000-0026bb765291', handle=96, characteristics=[BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=98, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000019-0000-1000-8000-0026bb765291', handle=100, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=101)]), BluetoothGATTCharacteristic(uuid='00000037-0000-1000-8000-0026bb765291', handle=103, properties=42, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=104), BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=105)])]), BluetoothGATTService(uuid='00000045-0000-1000-8000-0026bb765291', handle=112, characteristics=[BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=114, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='0000001d-0000-1000-8000-0026bb765291', handle=116, properties=42, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=117), BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=118)]), BluetoothGATTCharacteristic(uuid='0000001e-0000-1000-8000-0026bb765291', handle=120, properties=42, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=121), BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=122)]), BluetoothGATTCharacteristic(uuid='00000023-0000-1000-8000-0026bb765291', handle=124, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=125)])]), BluetoothGATTService(uuid='00000055-0000-1000-8000-0026bb765291', handle=240, characteristics=[BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=242, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='0000004c-0000-1000-8000-0026bb765291', handle=244, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=245)]), BluetoothGATTCharacteristic(uuid='0000004e-0000-1000-8000-0026bb765291', handle=247, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=248)]), BluetoothGATTCharacteristic(uuid='0000004f-0000-1000-8000-0026bb765291', handle=250, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=251)]), BluetoothGATTCharacteristic(uuid='00000050-0000-1000-8000-0026bb765291', handle=253, properties=10, descriptors=[BluetoothGATTDescriptor(uuid='dc46f0fe-81d2-4616-b5d9-6abdd796939a', handle=254)])]), BluetoothGATTService(uuid='bd4ac610-0b45-11e3-8ffd-0800200c9a66', handle=256, characteristics=[BluetoothGATTCharacteristic(uuid='bd4ac611-0b45-11e3-8ffd-0800200c9a66', handle=258, properties=12, descriptors=[BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=259)]), BluetoothGATTCharacteristic(uuid='bd4ac612-0b45-11e3-8ffd-0800200c9a66', handle=261, properties=34, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=262), BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=263)]), BluetoothGATTCharacteristic(uuid='bd4ac613-0b45-11e3-8ffd-0800200c9a66', handle=265, properties=8, descriptors=[BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=266)]), BluetoothGATTCharacteristic(uuid='bd4ac614-0b45-11e3-8ffd-0800200c9a66', handle=268, properties=32, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=269), BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=270)]), BluetoothGATTCharacteristic(uuid='bd4ac615-0b45-11e3-8ffd-0800200c9a66', handle=272, properties=20, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=273), BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=274)]), BluetoothGATTCharacteristic(uuid='bd4ac616-0b45-11e3-8ffd-0800200c9a66', handle=276, properties=20, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=277), BluetoothGATTDescriptor(uuid='00002901-0000-1000-8000-00805f9b34fb', handle=278)]), BluetoothGATTCharacteristic(uuid='e604e95d-a759-4817-87d3-aa005083a0d1', handle=280, properties=2, descriptors=[])])]) 2022-11-03 23:44:51.356 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services saved 2022-11-03 23:44:51.641 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00#\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd2\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:44:52.567 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 57:7C:5C:1F:57:8E AdvertisementData(manufacturer_data={76: b'\x10\x05u\x1c\xf7#\xfb'}, tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-03 23:44:56.575 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:44:56.575 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:44:58.076 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:44:58.076 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:44:59.577 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:44:59.577 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:01.078 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:01.078 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:02.579 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:02.579 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:03.205 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00z\xdc\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-97) connectable: True match: set() rssi: -97 2022-11-03 23:45:04.081 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:04.081 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:05.582 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:05.582 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:07.083 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:07.083 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:09.064 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00$\xe4\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-85) connectable: True match: set() rssi: -85 2022-11-03 23:45:09.065 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:09.066 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:10.567 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:10.567 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:11.100 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=31.4375, missing_state=False) 2022-11-03 23:45:11.615 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00%\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:45:12.068 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:12.068 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:13.307 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00{\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xc9\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-91) connectable: True match: set() rssi: -91 2022-11-03 23:45:15.338 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:15.338 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:16.162 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 68:F5:7F:76:B2:38 AdvertisementData(servicedata={'0000fd69-0000-1000-8000-00805f9b34fb': b'\x10;[\xb3\xb2\r\xd8\x18\xc7\xbe\xa3\xd7R\x00\x1cER\x7f.'}, service_uuids=['0000fd69-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-93) connectable: True match: set() rssi: -93 2022-11-03 23:45:17.787 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:17.788 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:22.782 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:45:22.782 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:45:22.783 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:45:22.783 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:45:22.791 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:45:23.166 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:45:26.156 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00&\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:45:27.765 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\x13\x08\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-03 23:45:32.109 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:45:32.110 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:45:32.110 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-03 23:45:32.110 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:45:32.118 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services hit 2022-11-03 23:45:35.710 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b"q \x98\x00'\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd2\x00"}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:45:38.365 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:38.366 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:39.365 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00}\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-94) connectable: True match: set() rssi: -94 2022-11-03 23:45:39.867 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:39.867 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:41.369 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:41.369 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:42.871 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:42.872 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:44.373 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:44.373 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:45.874 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:45.874 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:47.375 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:47.375 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:47.591 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 174803132): LightState(key=174803132, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:45:47.621 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 2472414331): LightState(key=2472414331, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:45:48.876 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:48.877 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:50.379 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:50.379 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:52.017 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:52.017 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:53.518 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:53.518 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:55.020 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:55.020 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:55.665 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00)\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-81) connectable: True match: set() rssi: -81 2022-11-03 23:45:56.521 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:56.521 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:58.023 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:58.023 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:59.524 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:45:59.524 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:01.025 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:01.025 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:01.483 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 1C:0D:34:4A:CC:EA AdvertisementData(manufacturer_data={6: b'\x01\t "?\xb9E\xd0v7\xc7\x16 \x85\x02\xa0R05\x98\x9a4\xbd\xd5[\xd8\xdf'}, tx_power=-127, rssi=-64) connectable: True match: set() rssi: -64 2022-11-03 23:46:02.521 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:46:02.522 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:46:02.522 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:46:02.522 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:46:02.526 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:46:02.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:46:02.543 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:46:03.633 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00*\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-87) connectable: True match: set() rssi: -87 2022-11-03 23:46:05.613 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:46:05.613 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:46:05.614 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:46:06.165 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:46:06.223 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:46:10.428 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:46:10.429 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:46:10.429 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:46:10.429 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:46:11.098 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=31.4375, missing_state=False) 2022-11-03 23:46:13.684 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00+\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd2\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:46:20.098 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:46:20.160 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:46:25.035 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services hit 2022-11-03 23:46:25.839 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 174803132): LightState(key=174803132, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:46:25.928 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 2472414331): LightState(key=2472414331, state=True, brightness=0.6, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-03 23:46:30.362 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:30.362 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:31.863 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:31.863 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:33.364 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:33.364 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:34.269 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x83\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xc8\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-03 23:46:34.865 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:34.865 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:36.366 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:36.367 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:37.648 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00-\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-86) connectable: True match: set() rssi: -86 2022-11-03 23:46:37.868 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:37.868 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:39.369 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:39.370 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:40.871 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:40.871 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:42.372 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:42.372 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:43.671 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 40:0D:16:08:96:80 AdvertisementData(service_data={'0000fd69-0000-1000-8000-00805f9b34fb': b"\x10\x9d#\xa2\xdb\xf8\xf0\xf8w\\xd9@'R\x00\x1cES8\x81"}, service_uuids=['0000fd69-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-03 23:46:43.873 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:43.874 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:45.375 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:45.375 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:45.641 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00.\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: set() rssi: -82 2022-11-03 23:46:46.233 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x84\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-03 23:46:46.876 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:46.876 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:48.378 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:48.378 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:49.878 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:49.879 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:51.380 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:51.380 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:52.882 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:52.882 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:54.382 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:54.383 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:46:54.526 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:46:54.527 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:46:55.243 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x85\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-03 23:46:55.883 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:46:56.203 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:46:56.694 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00/\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd0\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:47:02.225 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\x13\t\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-73) connectable: True match: set() rssi: -73 2022-11-03 23:47:02.513 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:02.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:02.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:02.514 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:47:02.642 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x000\xe4\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:47:03.065 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:47:03.128 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:47:08.248 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:08.248 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:08.248 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:08.248 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=62 2022-11-03 23:47:08.249 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:47:08.801 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:47:08.861 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:47:11.092 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=31.5, missing_state=False) 2022-11-03 23:47:14.392 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:14.393 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:14.393 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:14.394 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:14.394 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-03 23:47:14.394 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:47:14.396 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services hit 2022-11-03 23:47:24.769 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x002\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:47:33.233 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x89\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-94) connectable: True match: set() rssi: -94 2022-11-03 23:47:33.775 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 4E:4A:A7:22:67:77 AdvertisementData(manufacturer_data={76: b'\x10\x03}\r\x18\x0f\x05\x805\xad\x01\xfb'}, tx_power=-127, rssi=-96) connectable: True match: set() rssi: -96 2022-11-03 23:47:35.107 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:35.107 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:36.608 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:36.608 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:38.109 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:38.110 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:39.611 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:39.611 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:41.112 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:41.112 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:42.613 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:42.614 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:44.114 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:44.115 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:44.513 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:47:44.513 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:47:44.513 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:47:44.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:47:44.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:47:44.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:47:44.514 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:47:44.514 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:47:45.615 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:47:45.646 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\x13\n\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-03 23:47:45.658 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-03 23:47:50.540 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1 2022-11-03 23:47:50.541 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Cached services hit 2022-11-03 23:47:54.637 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x005\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-03 23:47:55.769 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:55.769 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:57.271 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:57.272 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:58.773 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:47:58.773 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:00.276 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:00.277 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:01.616 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x006\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-03 23:48:01.779 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:01.779 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:03.282 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:03.282 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:04.784 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:04.785 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:06.288 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:06.288 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:07.790 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:07.790 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:09.293 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:09.293 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:10.794 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:10.794 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:11.095 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=31.5, missing_state=False) 2022-11-03 23:48:11.652 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x007\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd0\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: set() rssi: -82 2022-11-03 23:48:12.295 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:12.295 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:13.798 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:13.798 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:15.217 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchen-ble-proxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x8d\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-03 23:48:15.299 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:15.300 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:16.801 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:16.801 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:18.303 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:18.303 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:19.804 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:19.804 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=0 result=False 2022-11-03 23:48:20.542 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:48:20.542 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:48:20.542 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=300 error=19 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchen-ble-proxy: Aug - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-03 23:48:20.543 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1 2022-11-03 23:48:21.305 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchen-ble-proxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-03 23:48:21.383 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=1 free=0 limit=1

bdraco commented 2 years ago

2022-11-03 23:44:38.956 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchen-ble-proxy: BLE connection limits: used=0 free=1 limit=1

The esphome device is still running the older firmware. The new firmware has a limit of 3

austwhite commented 2 years ago

I just uploaded the firmware on this one tonight, straight after updating to ESPHome 2022.10.2 and HA 2022.11 I also re-uploaded it prior to logging this issue.
Okay let me wipe the ESP32 and do it from scratch as ESPHome in HA is not advising it needs updating

bdraco commented 2 years ago

You'll need to install the development firmware. ESPHome 2022.10.2 doesn't have the fix

austwhite commented 2 years ago

Okay. That makes more sense now

bdraco commented 2 years ago
git clone https://github.com/esphome/esphome
cd esphome
pip3 install --upgrade .
esphome compile your_yaml_file.yaml
esphome upload your_yaml_file.yaml
austwhite commented 2 years ago

I uninstalled the production version and went to the Dev version. It seems to not get the slot error, but now it just seems to refuse to connect. Note my ESP32 is less than 1 metre in direct line of site to the lock. Can you check the logs and see what I have done wrong? I assume I must be getting something wrong in this :)

2022-11-04 20:07:06.101 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1e\xdc\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-89) connectable: True match: set() rssi: -89 2022-11-04 20:07:07.763 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:07:11.101 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1f\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:07:15.096 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:07:23.084 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00 \xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-89) connectable: True match: set() rssi: -89 2022-11-04 20:07:23.611 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:07:30.283 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=3 result=True 2022-11-04 20:07:30.295 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:30.985 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-77) connectable: True match: set() rssi: -77 2022-11-04 20:07:31.993 DEBUG (MainThread) [homeassistant.components.bluetooth] Triggering bluetooth usb discovery 2022-11-04 20:07:35.591 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:07:35.592 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:07:35.592 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-04 20:07:35.593 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:35.594 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Cached services hit 2022-11-04 20:07:35.677 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 02:FB:A9:2D:F5:63 AdvertisementData(manufacturer_data={6: b"\x01\t &\x86vS\xc2\xd0\xf6E\x0f\x05\x1e79n\xad\x1a\xf2\x82\xfe'y\x92+\xb1"}, tx_power=-127, rssi=-69) connectable: True match: set() rssi: -69 2022-11-04 20:07:36.600 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1c\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-04 20:07:36.999 DEBUG (MainThread) [homeassistant.components.bluetooth] Rediscovered adapters: {} 2022-11-04 20:07:40.782 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:07:40.835 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:43.804 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: FF:FF:10:38:55:E4 AdvertisementData(local_name='Tag-It ', manufacturer_data={261: b'\xff\xff\x108U\xe4f\x02\x01\x03\x00'}, service_uuids=['0000ffe0-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-99) connectable: True match: set() rssi: -99 2022-11-04 20:07:44.074 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00"\xdc\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:07:51.258 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 55:DF:03:E7:DF:CA AdvertisementData(manufacturer_data={224: b'\x00)\xca\xebo`'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-67) connectable: True match: set() rssi: -67 2022-11-04 20:07:54.109 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00#\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-96) connectable: True match: set() rssi: -96 2022-11-04 20:07:58.287 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:07:58.347 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:58.739 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-73) connectable: True match: set() rssi: -73 2022-11-04 20:08:02.971 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=38.125, missing_state=False) 2022-11-04 20:08:03.839 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.839 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:08:03.841 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Cached services hit 2022-11-04 20:08:06.091 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00$\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-88) connectable: True match: set() rssi: -88 2022-11-04 20:08:09.044 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:08:09.094 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:08:14.100 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00%\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:08:14.606 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00 \xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-100) connectable: True match: set() rssi: -100 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome] kitchenbleproxy: 192.168.0.120 disconnected, running disconnected callbacks 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: ESP device disconnected 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: ESP device disconnected 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-04 20:08:36.825 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Connecting scanner version=2, connectable=True 2022-11-04 20:08:36.949 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:08:37.158 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b"q \x98\x00'\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00"}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-90) connectable: True match: set() rssi: -90 2022-11-04 20:08:39.589 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:08:43.179 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-04 20:08:43.586 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00#\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-94) connectable: True match: set() rssi: -94 2022-11-04 20:08:44.080 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00(\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-93) connectable: True match: set() rssi: -93

bdraco commented 2 years ago

It looks like it’s actually connectingTry enabling debug logs for the following as well so we can get a better view of what’s going on:yalexs_blebleak_retry_connectorSent from my iPhoneOn Nov 4, 2022, at 10:13 AM, austwhite @.***> wrote: I uninstalled the production version and went to the Dev version. It seems to not get the slot error, but now it just seems to refuse to connect. Note my ESP32 is less than 1 metre in direct line of site to the lock. Can you check the logs and see what I have done wrong? 2022-11-04 20:07:06.101 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1e\xdc\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-89) connectable: True match: set() rssi: -89 2022-11-04 20:07:07.763 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:07:11.101 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1f\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:07:15.096 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:07:23.084 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00 \xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-89) connectable: True match: set() rssi: -89 2022-11-04 20:07:23.611 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:07:30.283 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=3 result=True 2022-11-04 20:07:30.295 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:30.985 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x7f\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-77) connectable: True match: set() rssi: -77 2022-11-04 20:07:31.993 DEBUG (MainThread) [homeassistant.components.bluetooth] Triggering bluetooth usb discovery 2022-11-04 20:07:35.591 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:07:35.592 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:07:35.592 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-04 20:07:35.593 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:35.594 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Cached services hit 2022-11-04 20:07:35.677 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 02:FB:A9:2D:F5:63 AdvertisementData(manufacturer_data={6: b"\x01\t &\x86vS\xc2\xd0\xf6E\x0f\x05\x1e79n\xad\x1a\xf2\x82\xfe'y\x92+\xb1"}, tx_power=-127, rssi=-69) connectable: True match: set() rssi: -69 2022-11-04 20:07:36.600 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\x1c\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-04 20:07:36.999 DEBUG (MainThread) [homeassistant.components.bluetooth] Rediscovered adapters: {} 2022-11-04 20:07:40.782 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:07:40.835 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:43.804 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: FF:FF:10:38:55:E4 AdvertisementData(local_name='Tag-It ', manufacturer_data={261: b'\xff\xff\x108U\xe4f\x02\x01\x03\x00'}, service_uuids=['0000ffe0-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-99) connectable: True match: set() rssi: -99 2022-11-04 20:07:44.074 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00"\xdc\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:07:51.258 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 55:DF:03:E7:DF:CA AdvertisementData(manufacturer_data={224: b'\x00)\xca\xebo`'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-67) connectable: True match: set() rssi: -67 2022-11-04 20:07:54.109 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00#\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-96) connectable: True match: set() rssi: -96 2022-11-04 20:07:58.287 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:07:58.347 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:07:58.739 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-73) connectable: True match: set() rssi: -73 2022-11-04 20:08:02.971 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=38.125, missing_state=False) 2022-11-04 20:08:03.839 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.839 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=True mtu=300 error=0 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: connected, registering for disconnected callbacks 2022-11-04 20:08:03.840 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:08:03.841 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Cached services hit 2022-11-04 20:08:06.091 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00$\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-88) connectable: True match: set() rssi: -88 2022-11-04 20:08:09.044 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:08:09.094 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:08:14.100 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00%\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-95) connectable: True match: set() rssi: -95 2022-11-04 20:08:14.606 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00 \xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-100) connectable: True match: set() rssi: -100 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome] kitchenbleproxy: 192.168.0.120 disconnected, running disconnected callbacks 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: ESP device disconnected 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: ESP device disconnected 2022-11-04 20:08:36.527 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: BLE device disconnected 2022-11-04 20:08:36.825 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Connecting scanner version=2, connectable=True 2022-11-04 20:08:36.949 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:08:37.158 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b"q \x98\x00'\xdc\x89l\x8d|\xc4\r\x04\x10\x02\xcc\x00"}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-90) connectable: True match: set() rssi: -90 2022-11-04 20:08:39.589 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='Aug', manufacturer_data={465: b'\x00', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:08:43.179 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b'\xe7\x80\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x02\x02\x02\x00\x00'}, tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-04 20:08:43.586 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00#\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-94) connectable: True match: set() rssi: -94 2022-11-04 20:08:44.080 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00(\xdc\x89l\x8d|\xc4\r\x07\x10\x03\x00\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-93) connectable: True match: set() rssi: -93

—Reply to this email directly, view it on GitHub, or unsubscribe.You are receiving this because you were mentioned.Message ID: @.***>

austwhite commented 2 years ago

Looks like GitHub e-mail ate it a bit:

yalexs_ble bleak_retry_connector homeassistant.components.esphome

austwhite commented 2 years ago

Hopefully this will contain something helpful.

2022-11-04 20:37:37.158 DEBUG (MainThread) [yalexs_ble.push] Waiting for advertisement callbacks for FRONT DOOR (78:9C:85:01:CB:3D) 2022-11-04 20:37:42.671 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Connecting scanner version=2, connectable=True 2022-11-04 20:37:43.077 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:37:43.240 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 174803132): LightState(key=174803132, state=True, brightness=0.9019608, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-04 20:37:43.306 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 2472414331): LightState(key=2472414331, state=True, brightness=0.9019608, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-04 20:37:43.307 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 3184812465): LightState(key=3184812465, state=False, brightness=1.0, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-04 20:37:43.312 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.LightState'>, 3184812470): LightState(key=3184812470, state=False, brightness=1.0, color_mode=3, color_brightness=1.0, red=1.0, green=1.0, blue=1.0, white=1.0, color_temperature=0.0, cold_white=1.0, warm_white=1.0, effect='') 2022-11-04 20:37:43.326 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=39.0, missing_state=False) 2022-11-04 20:37:43.412 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SwitchState'>, 320112106): SwitchState(key=320112106, state=False) 2022-11-04 20:38:02.988 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=39.0625, missing_state=False) 2022-11-04 20:38:30.266 DEBUG (MainThread) [homeassistant.components.bluetooth] Triggering bluetooth usb discovery 2022-11-04 20:38:35.368 DEBUG (MainThread) [yalexs_ble.push] Waiting for advertisement callbacks for FRONT DOOR (78:9C:85:01:CB:3D) 2022-11-04 20:38:36.158 DEBUG (MainThread) [homeassistant.components.bluetooth] Rediscovered adapters: {} 2022-11-04 20:39:02.985 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=39.0, missing_state=False) 2022-11-04 20:39:28.557 DEBUG (MainThread) [homeassistant.components.esphome] kitchenbleproxy: 192.168.0.120 disconnected, running disconnected callbacks 2022-11-04 20:39:28.866 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Connecting scanner version=2, connectable=True 2022-11-04 20:39:28.948 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:39:29.154 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: F9:C8:D8:80:3C:96 AdvertisementData(local_name='S', manufacturer_data={880: b'\x05\xe4\x00\x00Kitchen0 '}, tx_power=-127, rssi=-80) connectable: True match: set() rssi: -80 2022-11-04 20:39:29.156 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 31:9A:74:40:6C:82 AdvertisementData(manufacturer_data={6: b'\x01\t \x02l\x0f\x8e\x109\t\x08\xe7\xe5\xaf\x99\xa5\xf2\xf2&\xc5\xd55\x96g;N\x06'}, tx_power=-127, rssi=-58) connectable: True match: set() rssi: -58 2022-11-04 20:39:29.255 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: D4:84:57:4B:35:D3 AdvertisementData(local_name='net', manufacturer_data={1704: b'\x01\x01\x002\xd4\x84WK5\xd3'}, service_uuids=['0000ff90-0000-1000-8000-00805f9b34fb', '0000ff80-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-83) connectable: True match: set() rssi: -83 2022-11-04 20:39:29.281 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 51:65:DF:75:88:27 AdvertisementData(manufacturer_data={224: b'\x14\x02\xca\x92n\xad'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-70) connectable: True match: set() rssi: -70 2022-11-04 20:39:29.563 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 4E:4E:4E:7A:8A:45 AdvertisementData(manufacturer_data={224: b'\x00 \xca8\xda\xac'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-61) connectable: True match: set() rssi: -61 2022-11-04 20:39:29.564 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: FC:4F:A2:51:AD:E2 AdvertisementData(local_name='Bathroom globe 2', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'\x1b\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: set() rssi: -82 2022-11-04 20:39:29.564 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, tx_power=-127, rssi=-76) connectable: True match: {'homekit_controller'} rssi: -76 2022-11-04 20:39:29.564 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Scheduling update to happen in 0.5 seconds 2022-11-04 20:39:29.666 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: F5:FF:FA:CC:57:1F AdvertisementData(local_name='Bathroom globe 1', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'\x1b\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-86) connectable: True match: set() rssi: -86 2022-11-04 20:39:29.972 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 4D:49:C5:46:9A:9D AdvertisementData(manufacturer_data={224: b'\x00"\xca\x85\x1a\xde'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-74) connectable: True match: set() rssi: -74 2022-11-04 20:39:29.972 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:65:E7:93:81:9B AdvertisementData(local_name='S', manufacturer_data={880: b'\x05\xe4\x00\x00Living room0 '}, tx_power=-127, rssi=-79) connectable: True match: set() rssi: -79 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Update queued 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Queued update starting 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting update 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Acquiring lock 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting retry loop 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting update 2022-11-04 20:39:30.065 DEBUG (MainThread) [yalexs_ble.lock] FRONT DOOR (78:9C:85:01:CB:3D): Connecting to the lock 2022-11-04 20:39:30.065 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 1, last rssi: -76) 2022-11-04 20:39:30.066 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=3 result=True 2022-11-04 20:39:30.106 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:30.370 DEBUG (MainThread) [yalexs_ble.push] Canceling in progress update: <Task pending name='Task-15509' coro=<operation_lock.._async_wrap_operation_lock() running at /usr/local/lib/python3.10/site-packages/yalexs_ble/push.py:87> wait_for= cb=[Task.task_wakeup()]> 2022-11-04 20:39:30.373 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): In-progress update canceled due to lock operation or setup timeout 2022-11-04 20:39:30.373 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): In-progress update canceled 2022-11-04 20:39:30.791 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 51:91:91:E6:55:8A AdvertisementData(manufacturer_data={224: b"\x00!\xcalX'"}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-88) connectable: True match: set() rssi: -88 2022-11-04 20:39:31.021 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 44:C6:5D:82:DD:3B AdvertisementData(manufacturer_data={76: b'\x10\x05\x01\x14}\xb2\xef'}, tx_power=-127, rssi=-92) connectable: True match: set() rssi: -92 2022-11-04 20:39:31.832 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:31.834 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:39:31.846 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=0 free=3 limit=3 2022-11-04 20:39:31.917 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 61:8D:67:AE:22:C7 AdvertisementData(manufacturer_data={224: b'\x00)\xca\xebo`'}, service_data={'0000fe9f-0000-1000-8000-00805f9b34fb': b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'}, service_uuids=['0000fe9f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-70) connectable: True match: set() rssi: -70 2022-11-04 20:39:31.918 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 05:89:D9:38:B6:F4 AdvertisementData(manufacturer_data={6: b'\x01\t "\xd1\x12\xf5\xfa\xfa\x16\xde\x80H@c\xce\xb9~\t\xc5\xbf(JK\xfeW}'}, tx_power=-127, rssi=-48) connectable: True match: set() rssi: -48 2022-11-04 20:39:31.934 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: FB:69:00:31:AC:56 AdvertisementData(local_name='Bathroom fan', service_data={'0000fe0f-0000-1000-8000-00805f9b34fb': b'Z\x10l\x96\x02'}, service_uuids=['0000fe0f-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-81) connectable: True match: set() rssi: -81 2022-11-04 20:39:32.123 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-04 20:39:32.561 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\xdc\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: {'xiaomi_ble'} rssi: -82 2022-11-04 20:39:32.563 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:E4 - C4:7C:8D:6C:89:E4 -> kitchenbleproxy: Connecting (attempt: 1, last rssi: -82) 2022-11-04 20:39:32.563 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=3 result=True 2022-11-04 20:39:32.583 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:32.859 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:39:33.658 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-77) connectable: True match: set() rssi: -77 2022-11-04 20:39:34.581 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-04 20:39:34.802 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 80:7A:BF:E9:E4:21 AdvertisementData(local_name='HTC BS 216A96', service_uuids=['0000cb00-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-65) connectable: True match: set() rssi: -65 2022-11-04 20:39:35.008 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 80:7A:BF:E9:E2:5A AdvertisementData(local_name='HTC BS 5A624B', service_uuids=['0000cb00-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-84) connectable: True match: set() rssi: -84 2022-11-04 20:39:38.081 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:DC AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\xe1\xdc\x89l\x8d|\xc4\r\x08\x10\x01\x02'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-94) connectable: True match: {'xiaomi_ble'} rssi: -94 2022-11-04 20:39:38.083 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:DC - C4:7C:8D:6C:89:DC -> kitchenbleproxy: Connecting (attempt: 1, last rssi: -94) 2022-11-04 20:39:38.084 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:39:38.095 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:38.573 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:39:39.700 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:39.701 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:39.701 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:39.701 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:E4 - C4:7C:8D:6C:89:E4 -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 1, last rssi: -82) 2022-11-04 20:39:40.252 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:E4 - C4:7C:8D:6C:89:E4 -> kitchenbleproxy: Connecting (attempt: 2, last rssi: -82) 2022-11-04 20:39:40.252 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:39:40.333 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:40.372 DEBUG (MainThread) [yalexs_ble.push] Waiting for advertisement callbacks for FRONT DOOR (78:9C:85:01:CB:3D) 2022-11-04 20:39:40.372 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Scheduling update to happen in 0.5 seconds 2022-11-04 20:39:40.873 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Update queued 2022-11-04 20:39:40.873 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Queued update starting 2022-11-04 20:39:40.873 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting update 2022-11-04 20:39:40.873 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Acquiring lock 2022-11-04 20:39:40.873 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting retry loop 2022-11-04 20:39:40.874 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Starting update 2022-11-04 20:39:40.874 DEBUG (MainThread) [yalexs_ble.lock] FRONT DOOR (78:9C:85:01:CB:3D): Connecting to the lock 2022-11-04 20:39:40.874 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 1, last rssi: -76) 2022-11-04 20:39:40.874 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-04 20:39:40.945 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=3 free=0 limit=3 2022-11-04 20:39:43.302 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:43.303 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:43.303 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:43.303 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 1, last rssi: -76) 2022-11-04 20:39:43.309 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:43.633 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\xdd\xe4\x89l\x8d|\xc4\r\x04\x10\x02\xd3\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-87) connectable: True match: set() rssi: -87 2022-11-04 20:39:43.854 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 2, last rssi: -76) 2022-11-04 20:39:43.854 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-04 20:39:43.951 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=3 free=0 limit=3 2022-11-04 20:39:51.188 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Connection state changed to connected=True mtu=23 error=0 2022-11-04 20:39:51.188 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Connection state changed to connected=True mtu=23 error=0 2022-11-04 20:39:51.188 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: connected, registering for disconnected callbacks 2022-11-04 20:39:51.188 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=3 free=0 limit=3 2022-11-04 20:39:51.189 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Cached services miss 2022-11-04 20:39:51.392 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Got services: ESPHomeBluetoothGATTServices(address=216039227689444, services=[BluetoothGATTService(uuid='00001800-0000-1000-8000-00805f9b34fb', handle=1, characteristics=[BluetoothGATTCharacteristic(uuid='00002a00-0000-1000-8000-00805f9b34fb', handle=3, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a01-0000-1000-8000-00805f9b34fb', handle=5, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a02-0000-1000-8000-00805f9b34fb', handle=7, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='00002a04-0000-1000-8000-00805f9b34fb', handle=9, properties=2, descriptors=[])]), BluetoothGATTService(uuid='00001801-0000-1000-8000-00805f9b34fb', handle=12, characteristics=[BluetoothGATTCharacteristic(uuid='00002a05-0000-1000-8000-00805f9b34fb', handle=14, properties=34, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=15)])]), BluetoothGATTService(uuid='0000fe95-0000-1000-8000-00805f9b34fb', handle=16, characteristics=[BluetoothGATTCharacteristic(uuid='00000001-0000-1000-8000-00805f9b34fb', handle=18, properties=24, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=19)]), BluetoothGATTCharacteristic(uuid='00000002-0000-1000-8000-00805f9b34fb', handle=21, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000004-0000-1000-8000-00805f9b34fb', handle=23, properties=18, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000007-0000-1000-8000-00805f9b34fb', handle=25, properties=8, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000010-0000-1000-8000-00805f9b34fb', handle=27, properties=8, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000013-0000-1000-8000-00805f9b34fb', handle=29, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='00000014-0000-1000-8000-00805f9b34fb', handle=31, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00001001-0000-1000-8000-00805f9b34fb', handle=33, properties=16, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=34)])]), BluetoothGATTService(uuid='0000fef5-0000-1000-8000-00805f9b34fb', handle=35, characteristics=[BluetoothGATTCharacteristic(uuid='8082caa8-41a6-4021-91c6-56f9b954cc34', handle=37, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='724249f0-5ec3-4b5f-8804-42345af08651', handle=39, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='6c53db25-47a1-45fe-a022-7c92fb334fd4', handle=41, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='9d84b9a3-000c-49d8-9183-855b673fda31', handle=43, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='457871e8-d516-4ca1-9116-57d0b17b9cb2', handle=45, properties=14, descriptors=[]), BluetoothGATTCharacteristic(uuid='5f78df94-798c-46f5-990a-b3eb6a065c88', handle=47, properties=18, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=48)])]), BluetoothGATTService(uuid='00001204-0000-1000-8000-00805f9b34fb', handle=49, characteristics=[BluetoothGATTCharacteristic(uuid='00001a00-0000-1000-8000-00805f9b34fb', handle=51, properties=10, descriptors=[]), BluetoothGATTCharacteristic(uuid='00001a01-0000-1000-8000-00805f9b34fb', handle=53, properties=26, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=54)]), BluetoothGATTCharacteristic(uuid='00001a02-0000-1000-8000-00805f9b34fb', handle=56, properties=2, descriptors=[BluetoothGATTDescriptor(uuid='00001a02-0000-1000-8000-00805f9b34fb', handle=57)])]), BluetoothGATTService(uuid='00001206-0000-1000-8000-00805f9b34fb', handle=58, characteristics=[BluetoothGATTCharacteristic(uuid='00001a11-0000-1000-8000-00805f9b34fb', handle=60, properties=2, descriptors=[]), BluetoothGATTCharacteristic(uuid='00001a10-0000-1000-8000-00805f9b34fb', handle=62, properties=26, descriptors=[BluetoothGATTDescriptor(uuid='00002902-0000-1000-8000-00805f9b34fb', handle=63)]), BluetoothGATTCharacteristic(uuid='00001a12-0000-1000-8000-00805f9b34fb', handle=65, properties=2, descriptors=[BluetoothGATTDescriptor(uuid='00001a12-0000-1000-8000-00805f9b34fb', handle=66)])])]) 2022-11-04 20:39:51.394 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Cached services saved 2022-11-04 20:39:51.394 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:E4 - C4:7C:8D:6C:89:E4 -> kitchenbleproxy: Connected (attempt: 2, last rssi: -82) 2022-11-04 20:39:51.522 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Out of connection slots, waiting for a free one 2022-11-04 20:39:51.579 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: Connection state changed to connected=False mtu=0 error=22 2022-11-04 20:39:51.579 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: Flower care - C4:7C:8D:6C:89:E4: BLE device disconnected 2022-11-04 20:39:51.579 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:55.338 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:DC - C4:7C:8D:6C:89:DC -> kitchenbleproxy: Timed out trying to connect (attempt: 1, last rssi: -94) 2022-11-04 20:39:55.589 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:DC - C4:7C:8D:6C:89:DC -> kitchenbleproxy: Connecting (attempt: 2, last rssi: -94) 2022-11-04 20:39:55.589 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-04 20:39:55.619 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:39:56.517 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 46:24:89:13:CE:70 AdvertisementData(manufacturer_data={76: b'\x10\x06k\x1d\tW\xa8\x80'}, tx_power=-127, rssi=-96) connectable: True match: set() rssi: -96 2022-11-04 20:39:58.951 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:58.951 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:58.951 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:39:58.952 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 2, last rssi: -76) 2022-11-04 20:39:58.952 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:58.953 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:39:59.505 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 3, last rssi: -76) 2022-11-04 20:39:59.505 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:39:59.566 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:01.837 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:40:01.838 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Scheduling update to happen in 0.5 seconds 2022-11-04 20:40:02.339 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Rescheduling update since one already in progress 2022-11-04 20:40:02.340 DEBUG (MainThread) [yalexs_ble.push] FRONT DOOR (78:9C:85:01:CB:3D): Scheduling update to happen in 29.5 seconds 2022-11-04 20:40:02.741 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:02.742 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:02.742 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:02.743 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:02.744 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:02.745 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 3, last rssi: -76) 2022-11-04 20:40:02.991 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenledstrip: dispatching update with key (<class 'aioesphomeapi.model.SensorState'>, 1013851870): SensorState(key=1013851870, state=39.0625, missing_state=False) 2022-11-04 20:40:03.296 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 4, last rssi: -76) 2022-11-04 20:40:03.297 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:40:03.457 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:04.481 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:04.481 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:04.481 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:04.482 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:04.482 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 4, last rssi: -76) 2022-11-04 20:40:04.483 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:05.034 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 5, last rssi: -76) 2022-11-04 20:40:05.034 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:40:05.121 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:05.404 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={465: b'\x00', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, service_uuids=['bd4ac610-0b45-11e3-8ffd-0800200c9a66'], tx_power=-127, rssi=-76) connectable: True match: set() rssi: -76 2022-11-04 20:40:06.037 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:06.037 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:06.037 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:06.038 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:06.038 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:06.038 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 5, last rssi: -76) 2022-11-04 20:40:06.241 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: 78:9C:85:01:CB:3D AdvertisementData(local_name='AugSW38', manufacturer_data={76: b'\x06\xad\x01\x07\x87\xca\xe2\x86\xaa\x06\x00\x01\x00\x01\x02', 22: b',\x0e\xa5\xcc\xc9\xd5[\xc1D\x8d\x8aE<\x90\x14u\xd5k\x00\x00\x00\x00\x00'}, tx_power=-127, rssi=-75) connectable: True match: set() rssi: -75 2022-11-04 20:40:06.589 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 6, last rssi: -75) 2022-11-04 20:40:06.589 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:40:06.650 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:07.575 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\xdf\xe4\x89l\x8d|\xc4\r\x08\x10\x01\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: set() rssi: -82 2022-11-04 20:40:09.089 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:09.089 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:09.089 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:09.089 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:09.090 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 6, last rssi: -75) 2022-11-04 20:40:09.090 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:09.641 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 7, last rssi: -75) 2022-11-04 20:40:09.641 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:40:09.703 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:11.561 DEBUG (MainThread) [homeassistant.components.bluetooth.manager] kitchenbleproxy: C4:7C:8D:6C:89:E4 AdvertisementData(local_name='Flower care', service_data={'0000fe95-0000-1000-8000-00805f9b34fb': b'q \x98\x00\xe0\xe4\x89l\x8d|\xc4\r\t\x10\x02\x00\x00'}, service_uuids=['0000fe95-0000-1000-8000-00805f9b34fb'], tx_power=-127, rssi=-82) connectable: True match: set() rssi: -82 2022-11-04 20:40:12.842 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:DC - C4:7C:8D:6C:89:DC -> kitchenbleproxy: Timed out trying to connect (attempt: 2, last rssi: -94) 2022-11-04 20:40:13.094 DEBUG (MainThread) [bleak_retry_connector] C4:7C:8D:6C:89:DC - C4:7C:8D:6C:89:DC -> kitchenbleproxy: Connecting (attempt: 3, last rssi: -94) 2022-11-04 20:40:13.094 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=1 result=True 2022-11-04 20:40:13.261 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3 2022-11-04 20:40:16.463 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:16.463 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:16.464 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth.client] kitchenbleproxy: AugSW38 - 78:9C:85:01:CB:3D: Connection state changed to connected=False mtu=0 error=62 2022-11-04 20:40:16.464 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:16.465 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=1 free=2 limit=3 2022-11-04 20:40:16.466 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 7, last rssi: -75) 2022-11-04 20:40:17.018 DEBUG (MainThread) [bleak_retry_connector] FRONT DOOR (78:9C:85:01:CB:3D) - 78:9C:85:01:CB:3D -> kitchenbleproxy: Connecting (attempt: 8, last rssi: -75) 2022-11-04 20:40:17.019 DEBUG (MainThread) [homeassistant.components.esphome.bluetooth] kitchenbleproxy: Checking can connect, available=True, ble_connections_free=2 result=True 2022-11-04 20:40:17.122 DEBUG (MainThread) [homeassistant.components.esphome.entry_data] kitchenbleproxy: BLE connection limits: used=2 free=1 limit=3

bdraco commented 2 years ago

It’s definitely trying to establish the connection but it’s failed with kitchenbleproxy: Failed to connect: Error ESP_GATT_CONN_FAIL_ESTABLISH while connecting: Connection failed to establish, backing off: 0.55 (attempt: 7, last rssi: -75)

The good news is it’s trying to connect now so the original slot problem is solved.

The bad news is it tried 7 times to unsuccessfully connect. That error comes from the ESP32 esp-idf stack so it’s not something that can be adjusted in Home Assistant and it’s something that has to be solved on the esphome side most likely by upgrading the underlying esp-idf.

austwhite commented 2 years ago

Okay, so nothing I did wrong as such.... But needs ESPHome to fix it. Will I need to create a new ticket in ESP Home, or can this one with the current information be flagged to them?

bdraco commented 2 years ago

You’ll need to make a new esphome issue.

Also before you do, there is one more thing you can try. The lock has a range of about 20ft. How far away is the esp from the lock?

austwhite commented 2 years ago

The esp32 is less than 4 feet from the lock

austwhite commented 2 years ago

@bdraco Thanks for your help with this. This Bluetooth proxy thing really is the last missing piece in my Home Automation setup

ampersandru commented 2 years ago

@bdraco I am having the same errors trying to connect my August Lock Pro Gen3 via ESP32. Im new to ESP - I did set up an ESPHome docker but did not connect my ESP32 to it, I just flashed it using https://esphome.github.io/bluetooth-proxies/?utm_source=pocket_mylist and then connected it directly to HA with auto-discovery.

It was able to detect my two August locks, and configured them automatically with the offline key due to my August integration, but upon trying to initialize it in Home Assistant, getting the same errors as @austwhite

In the integrations page: Retrying setup: Could not connect; Try moving the Bluetooth adapter closer to L300AES

In logs: 2022-11-04 17:44:58.708 ERROR (MainThread) [yalexs_ble.lock] Garage Door (L300AES): Failed to connect to the lock: Garage Door (L300AES) - 78:9C:85:02:81:01 -> esp32-bluetooth-proxy-e43320: Failed to connect: No backend with an available connection slot that can reach address 78:9C:85:02:81:01 was found: The proxy/adapter is out of connection slots; Add additional proxies near this device

So as far as I know, this is not an ESPHome issue for me since I didn't even connect my ESP32 to ESPHome, just directly in HA? One of the ESP32s is about 6ft from that lock.

Home Assistant version 2022.11.0 Three ESP32s on Firmware: 2022.10.2

austwhite commented 2 years ago

@ampersandru The connection slots issues is fixed in the Dev version of ESPHome, but there is still a connection issue that @bdraco identified There have been a couple of issues opened on ESPHome related to this

ampersandru commented 2 years ago

ahh gotcha, guess Ill wait for the esp32 firmware to be publicly released

bdraco commented 1 year ago

Here is a branch with some esphome changes that should improve the performance and reliability:

https://github.com/esphome/issues/issues/3761#issuecomment-1324176431

I'm going to close this issue since there is already an esphome issue that is tracking this problem and that's where the changes need to happen