start_notify leads to hang on Linux and crash on Windows #949

TechHummel · 2022-08-20T11:49:34Z

TechHummel
Aug 20, 2022

This is a discussion as suggested by @dlech in #946.

I want to connect to a Bluetooth thermostat using Bleak. This has worked before using other Bluetooth modules, but I have not managed getting it to work with Bleak (while other people have).

With Windows, I receive an error mesasge and my code crashes while with Debian the code hangs.
Do you guys have any idea of what's going on?

This code is mainly copied from python-eq3bt module.

import asyncio
from bleak import BleakClient

mac = "x"

async def on_notification(handle, data):
    """Handle Callback from a Bluetooth (GATT) request."""
    # The notification handles are off-by-one compared to gattlib and bluepy
    pass


async def main(address):
    client = BleakClient(address)
    try:
        await client.connect(timeout=30.0)
        print("Connected succesful!")
        # 0x421 - 1, copied from eq3bt
        await client.start_notify(0x421 - 1, on_notification)
    finally:
        await client.disconnect()
        print("Disconnected!")

asyncio.run(main(mac))

Windows 11

2022-08-18 22:50:28,830 bleak.backends.winrt.scanner DEBUG: Received 00:1A:22:06:1B:EB: Unknown.
2022-08-18 22:50:28,835 bleak.backends.winrt.scanner DEBUG: 5 devices found. Watcher status: 3.
2022-08-18 22:50:28,835 bleak.backends.winrt.client DEBUG: Connecting to BLE device @ 00:1A:22:06:1B:EB
2022-08-18 22:50:33,808 bleak.backends.winrt.client DEBUG: session_status_changed_event_handler: id: <_bleak_winrt_Windows_Devices_Bluetooth.BluetoothDeviceId object at 0x00000217E39FACF0>, error: 0, status: 1
2022-08-18 22:50:33,810 bleak.backends.winrt.client DEBUG: Get Services...
2022-08-18 22:50:36,748 bleak.backends.winrt.client INFO: Services resolved for BleakClientWinRT (00:1A:22:06:1B:EB)
Connected succesful!
2022-08-18 22:50:36,783 bleak.backends.winrt.client DEBUG: Disconnecting from BLE device...
2022-08-18 22:50:40,051 bleak.backends.winrt.client DEBUG: session_status_changed_event_handler: id: <_bleak_winrt_Windows_Devices_Bluetooth.BluetoothDeviceId object at 0x00000217E39FDEB0>, error: 0, status: 0
Disconnected!
Traceback (most recent call last):
  File "c:\Users\user\VsCode\eq3bt\test.py", line 39, in <module>
    asyncio.run(main(mac))
  File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.9_3.9.3568.0_x64__qbz5n2kfra8p0\lib\asyncio\runners.py", line 44, in run
    return loop.run_until_complete(main)
  File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.9_3.9.3568.0_x64__qbz5n2kfra8p0\lib\asyncio\base_events.py", line 647, in run_until_complete
    return future.result()
  File "c:\Users\user\VsCode\eq3bt\test.py", line 33, in main
    await client.start_notify(0x421 - 1, on_notification)
  File "C:\Users\user\VsCode\eq3bt\.venv\lib\site-packages\bleak\backends\winrt\client.py", line 767, in start_notify
    await characteristic_obj.write_client_characteristic_configuration_descriptor_async(
OSError: [WinError -2140864507] The attribute must first be authenticated before it can be read or written [translated]

windows_11_eq3bt.pcapng.gz

Debian

Doesn't crash but hangs instead. Adapter set to hci1 via parameter (not shown in code).

bluetoothd -v: 5.55

2022-08-18 23:18:59,482 bleak.backends.bluezdbus.client DEBUG: Connecting to device @ 00:1A:22:06:1B:EB with hci1
2022-08-18 23:18:59,486 bleak.backends.bluezdbus.scanner DEBUG: cached devices: {}
2022-08-18 23:19:00,356 bleak.backends.bluezdbus.scanner DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci1): ['org.bluez.Adapter1', {'Discovering': <dbus_next.signature.Variant ('b', True)>}, []]
2022-08-18 23:19:00,364 bleak.backends.bluezdbus.scanner DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.Device1': {'Address': <dbus_next.signature.Variant ('s', 00:1A:22:06:1B:EB)>, 'AddressType': <dbus_next.signature.Variant ('s', public)>, 'Name': <dbus_next.signature.Variant ('s', CC-RT-BLE)>, 'Alias': <dbus_next.signature.Variant ('s', CC-RT-BLE)>, 'Paired': <dbus_next.signature.Variant ('b', False)>, 'Trusted': <dbus_next.signature.Variant ('b', False)>, 'Blocked': <dbus_next.signature.Variant ('b', False)>, 'LegacyPairing': <dbus_next.signature.Variant ('b', False)>, 'RSSI': <dbus_next.signature.Variant ('n', -63)>, 'Connected': <dbus_next.signature.Variant ('b', False)>, 'UUIDs': <dbus_next.signature.Variant ('as', ['3e135142-654f-9090-134a-a6ff5bb77046'])>, 'Adapter': <dbus_next.signature.Variant ('o', /org/bluez/hci1)>, 'ManufacturerData': <dbus_next.signature.Variant ('a{qv}', {0: <dbus_next.signature.Variant ('ay', b'\x00\x00\x00\x00\x00\x00\x00\x00\x00')>})>, 'ServicesResolved': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:00,371 bleak.backends.bluezdbus.scanner DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci1/dev_00_1A_22_06_1B_EB): ['org.bluez.Device1', {}, ['RSSI']]
2022-08-18 23:19:00,371 bleak.backends.bluezdbus.scanner DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci1): ['org.bluez.Adapter1', {'Discovering': <dbus_next.signature.Variant ('b', False)>}, []]
2022-08-18 23:19:02,363 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci1/dev_00_1A_22_06_1B_EB): ['org.bluez.Device1', {'Connected': <dbus_next.signature.Variant ('b', True)>}, []]
2022-08-18 23:19:02,534 bleak.backends.bluezdbus.client DEBUG: Connection successful (/org/bluez/hci1/dev_00_1A_22_06_1B_EB)
2022-08-18 23:19:02,535 bleak.backends.bluezdbus.client DEBUG: Waiting for ServicesResolved (/org/bluez/hci1/dev_00_1A_22_06_1B_EB)
2022-08-18 23:19:03,325 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 00001801-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,326 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200/char0210', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a05-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['read', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,328 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200/char0210/desc0220', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200/char0210)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,329 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 0000180a-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,330 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300/char0310', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a29-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,332 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300/char0320', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a24-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,334 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 3e135142-654f-9090-134a-a6ff5bb77046)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,335 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0410', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 3fa4585a-ce4a-3bad-db4b-b8df8179ea09)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read', 'write'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,337 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0420', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', d0e8434d-cd29-0996-af41-6c90f4e0eb2a)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['read', 'write', 'notify'])>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,338 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0420/desc0430', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0420)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,339 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 9e5d1e47-5c13-43a0-8635-82ad38a1386f)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,341 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff01', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', e3dd50bf-f7a7-4e99-838e-570a086c666b)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['write', 'notify', 'indicate'])>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,342 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff01/descff03', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff01)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,343 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff04', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 92e86c7a-d961-4091-b74f-2409e72efe36)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['write'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,344 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff06', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 347f7608-2e2d-47eb-913b-75d4edc4de3b)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2022-08-18 23:19:03,345 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci1/dev_00_1A_22_06_1B_EB): ['org.bluez.Device1', {'UUIDs': <dbus_next.signature.Variant ('as', ['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '3e135142-654f-9090-134a-a6ff5bb77046', '9e5d1e47-5c13-43a0-8635-82ad38a1386f'])>, 'ServicesResolved': <dbus_next.signature.Variant ('b', True)>}, []]
Connected succesful!
2022-08-18 23:19:34,245 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200/char0210/desc0220', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattDescriptor1']]
2022-08-18 23:19:34,247 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200/char0210', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,248 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0200', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattService1']]
2022-08-18 23:19:34,249 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300/char0310', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,249 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300/char0320', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,250 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0300', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattService1']]
2022-08-18 23:19:34,250 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0410', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,250 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0420/desc0430', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattDescriptor1']]
2022-08-18 23:19:34,250 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400/char0420', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,251 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/service0400', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattService1']]
2022-08-18 23:19:34,251 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff01/descff03', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattDescriptor1']]
2022-08-18 23:19:34,251 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff01', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,252 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff04', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,252 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00/charff06', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattCharacteristic1']]
2022-08-18 23:19:34,252 bleak.backends.bluezdbus.client DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci1/dev_00_1A_22_06_1B_EB/serviceff00', ['org.freedesktop.DBus.Properties', 'org.freedesktop.DBus.Introspectable', 'org.bluez.GattService1']]
^C2022-08-18 23:20:17,975 bleak.backends.bluezdbus.client DEBUG: Disconnecting (/org/bluez/hci1/dev_00_1A_22_06_1B_EB)
unhandled exception during asyncio.run() shutdown
task: <Task finished name='Task-1' coro=<main() done, defined at /home/user/eq3bt_mod/eq3bt/test2.py:12> exception=BleakDBusError('org.freedesktop.DBus.Error.UnknownObject', 'Method "Disconnect" with signature "" on interface "org.bluez.Device1" doesn\'t exist\n')>
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/runners.py", line 44, in run
    return loop.run_until_complete(main)
  File "/usr/lib/python3.9/asyncio/base_events.py", line 629, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 596, in run_forever
    self._run_once()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 1854, in _run_once
    event_list = self._selector.select(timeout)
  File "/usr/lib/python3.9/selectors.py", line 469, in select
    fd_event_list = self._selector.poll(timeout, max_ev)
KeyboardInterrupt

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/user/eq3bt_mod/eq3bt/test2.py", line 18, in main
    await client.start_notify(0x421 - 1, on_notification)
  File "/home/user/eq3bt_mod/eq3bt/bleak/backends/bluezdbus/client.py", line 923, in start_notify
    reply = await self._bus.call(
  File "/home/user/eq3bt_mod/eq3bt/.venv/lib/python3.9/site-packages/dbus_next/aio/message_bus.py", line 305, in call
    await future
asyncio.exceptions.CancelledError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/user/eq3bt_mod/eq3bt/test2.py", line 20, in main
    await client.disconnect()
  File "/home/user/eq3bt_mod/eq3bt/bleak/backends/bluezdbus/client.py", line 415, in disconnect
    assert_reply(reply)
  File "/home/user/eq3bt_mod/eq3bt/bleak/backends/bluezdbus/utils.py", line 23, in assert_reply
    raise BleakDBusError(reply.error_name, reply.body)
bleak.exc.BleakDBusError: [org.freedesktop.DBus.Error.UnknownObject] Method "Disconnect" with signature "" on interface "org.bluez.Device1" doesn't exist

Traceback (most recent call last):
  File "/home/user/eq3bt_mod/eq3bt/test2.py", line 23, in <module>
    asyncio.run(main(mac))
  File "/usr/lib/python3.9/asyncio/runners.py", line 44, in run
    return loop.run_until_complete(main)
  File "/usr/lib/python3.9/asyncio/base_events.py", line 629, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 596, in run_forever
    self._run_once()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 1854, in _run_once
    event_list = self._selector.select(timeout)
  File "/usr/lib/python3.9/selectors.py", line 469, in select
    fd_event_list = self._selector.poll(timeout, max_ev)
KeyboardInterrupt

debian_eq3bt.zip

Thanks!

dlech · 2022-08-20T15:58:41Z

dlech
Aug 20, 2022
Collaborator

OSError: [WinError -2140864507] The attribute must first be authenticated before it can be read or written [translated]

I think the error speaks for itself. The device must be paired/bonded before that particular characteristic can be used.

0 replies

dlech · 2022-08-20T16:03:22Z

dlech
Aug 20, 2022
Collaborator

Doesn't crash but hangs instead.

BlueZ may be waiting for the device to be paired since this characteristic requires authentication.

0 replies

TechHummel · 2022-09-06T20:10:16Z

TechHummel
Sep 6, 2022
Author

Thanks for your answer @dlech, and sorry for my late reply!
I am not exactly sure what I changed, but I am now receiving the following message when executing my script (Linux):

bleak.exc.BleakDBusError: [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Connection Rejected Due To Security Reasons)

This is with bleak 0.16.0, but I also get the same message with 0.14.3.

I think this might be related to #972. Pairing, as you suggested, was not necessary with bluepy.

The issue also occurs for other users of eq3bt rytilahti/python-eq3bt#56.

3 replies

dlech Sep 6, 2022
Collaborator

FYI, the error message is incorrect (fixed by #975). It is actually "Unlikely Error". You will need to do some logging of BlueZ to see what could be causing it (logging Bluetooth packets with wireshark, enabling debug messages in bluetoothd, etc.).

TechHummel Sep 6, 2022
Author

I can confirm: I used bleak from the develop branch, and I am now getting:

bleak.exc.BleakDBusError: [org.bluez.Error.Failed] Operation failed with ATT error: 0x0e (Unlikely Error)

I will try to do some more logging over the next couple of days (or weeks) - thanks!

Vignesh-30 Aug 29, 2024

Hi, can I check if you managed to find a solution to this error using bleak on windows?

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

start_notify leads to hang on Linux and crash on Windows #949

{{title}}

Replies: 3 comments 3 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

start_notify leads to hang on Linux and crash on Windows #949

TechHummel Aug 20, 2022

Replies: 3 comments · 3 replies

dlech Aug 20, 2022 Collaborator

dlech Aug 20, 2022 Collaborator

TechHummel Sep 6, 2022 Author

dlech Sep 6, 2022 Collaborator

TechHummel Sep 6, 2022 Author

Vignesh-30 Aug 29, 2024

TechHummel
Aug 20, 2022

Replies: 3 comments 3 replies

dlech
Aug 20, 2022
Collaborator

dlech
Aug 20, 2022
Collaborator

TechHummel
Sep 6, 2022
Author

dlech Sep 6, 2022
Collaborator

TechHummel Sep 6, 2022
Author