Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Support for Oregon Scientific RAR213HG #91

Open
vco1 opened this issue Aug 12, 2024 · 21 comments
Open

Support for Oregon Scientific RAR213HG #91

vco1 opened this issue Aug 12, 2024 · 21 comments
Labels
new device

Comments

@vco1
Copy link

vco1 commented Aug 12, 2024

Is it possible to add support for the Oregon Scientific RAR213HG weather station?

I looked into adding support myself, but am a bit confused about what needs to be added where. Especially if a new protocol is needed, and if so, what needs to be added where.

Information about a.o. the characteristics for this device can be found here: https://www.instructables.com/Connect-Raspberry-Pi-to-Oregon-Scientific-BLE-Weat/

Thanks in advance.
@devbis
Copy link
Owner

devbis commented Aug 12, 2024

I can add it, will you be able to test it by installing beta versions from git? (I'll send commands)

@vco1
Copy link
Author

vco1 commented Aug 12, 2024

Thanks!
Testing won't be a problem. I have a dev server available.

@devbis
Copy link
Owner

devbis commented Sep 15, 2024

@vco1
can you please start with

pip3 install -U https://github.com/devbis/ble2mqtt/archive/refs/heads/oregon.zip --no-cache-dir --force-reinstall

and trying to run this version.

Add this chunk to your config to devices section and replace the MAC.

        {
            "address": "11:22:33:aa:bb:de",
            "type": "oregon_weather",
            "interval": 60
        }

Or, if you use docker, switch to oregon branch and build the container.

I'll need logs here or on any pastebin site in case of errors

@vco1
Copy link
Author

vco1 commented Sep 24, 2024

@devbis
Thanks for the change. Due to some difficulties with my system it took some time to test this.

When running ble2mqtt with log level set to error I get the following:

2024-09-24 11:38:53 ERROR: Cannot connect to device IDT_TECHNOLOGY_LTD_IDT_RAR213_ca80589369ab
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/devices/base.py", line 145, in _read_with_timeout
    result = await aio.wait_for(
  File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for
    return fut.result()
  File "/usr/local/lib/python3.9/dist-packages/bleak/__init__.py", line 721, in read_gatt_char
    return await self._backend.read_gatt_char(char_specifier, **kwargs)
  File "/usr/local/lib/python3.9/dist-packages/bleak/backends/bluezdbus/client.py", line 758, in read_gatt_char
    assert_reply(reply)
  File "/usr/local/lib/python3.9/dist-packages/bleak/backends/bluezdbus/utils.py", line 20, in assert_reply
    raise BleakDBusError(reply.error_name, reply.body)
bleak.exc.BleakDBusError: [org.bluez.Error.NotPermitted] Read not permitted
2024-09-24 11:38:55 ERROR: Connection lost. Will retry in 10 seconds.
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/ble2mqtt.py", line 302, in _connect_mqtt_forever
    await self._run_device_tasks(mqtt_connection.disconnect_reason)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/ble2mqtt.py", line 273, in _run_device_tasks
    await handle_returned_tasks(*finished)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/tasks.py", line 79, in handle_returned_tasks
    await task_for_raise
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/manager.py", line 456, in manage_device
    await device.get_device_data()
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/devices/oregon_ht.py", line 256, in get_device_data
    if dev_props[1]:
TypeError: 'NoneType' object is not subscriptable
2024-09-24 11:39:05 ERROR: Connection lost. Will retry in 10 seconds.
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/ble2mqtt.py", line 302, in _connect_mqtt_forever
    await self._run_device_tasks(mqtt_connection.disconnect_reason)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/ble2mqtt.py", line 273, in _run_device_tasks
    await handle_returned_tasks(*finished)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/tasks.py", line 79, in handle_returned_tasks
    await task_for_raise
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt/manager.py", line 432, in manage_device
    async with BLUETOOTH_RESTARTING:
  File "/usr/lib/python3.9/asyncio/locks.py", line 14, in __aenter__
    await self.acquire()
  File "/usr/lib/python3.9/asyncio/locks.py", line 120, in acquire
    await fut
RuntimeError: Task <Task pending name='Task-30' coro=<DeviceManager.manage_device() running at /usr/local/lib/python3.9/dist-packages/ble2mqtt/manager.py:432> cb=[_wait.<locals>._on_completion() at /usr/lib/python3.9/asyncio/tasks.py:513]> got Future <Future pending> attached to a different loop
Restarting bluetooth (via systemctl): bluetooth.service.

@devbis
Copy link
Owner

devbis commented Sep 24, 2024

I removed that reading, can you please try again? Same command.

@vco1
Copy link
Author

vco1 commented Sep 24, 2024 •
edited
Loading

Connecting via gattool works fine. Same goes for bluetoothctl pair CA:80:58:93:69:AB .

ble2mqtt:

Restarting bluetooth (via systemctl): bluetooth.service.
2024-09-24 14:37:11 ERROR: [org.bluez.Error.NotReady] Resource Not Ready
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/ble2mqtt.py", line 209, in scan_devices_task
    await aio.wait_for(scanner.start(), 10)
  File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for
    return fut.result()
  File "/usr/local/lib/python3.9/dist-packages/bleak/__init__.py", line 203, in start
    await self._backend.start()
  File "/usr/local/lib/python3.9/dist-packages/bleak/backends/bluezdbus/scanner.py", line 185, in start
    self._stop = await manager.active_scan(
  File "/usr/local/lib/python3.9/dist-packages/bleak/backends/bluezdbus/manager.py", line 427, in active_scan
    assert_reply(reply)
  File "/usr/local/lib/python3.9/dist-packages/bleak/backends/bluezdbus/utils.py", line 20, in assert_reply
    raise BleakDBusError(reply.error_name, reply.body)
bleak.exc.BleakDBusError: [org.bluez.Error.NotReady] Resource Not Ready

@devbis
Copy link
Owner

devbis commented Sep 24, 2024

It is a common issue but not directly related to the app. You need to reset the dongle/bluetooth/something else. The app tries to do that automatically, but sometimes it doesn't work well.
Does this error continues all the time?

@vco1
Copy link
Author

vco1 commented Sep 24, 2024

Yes, the error occurs constantly. Currently running on a Pi with USB Bluetooth dongle. I will try to run it on a Pi4 with built-in bluetooth.

@devbis
Copy link
Owner

devbis commented Sep 24, 2024

You may try solutions from https://unix.stackexchange.com/questions/508221/bluetooth-service-running-but-bluetoothctl-says-org-bluez-error-notready

@vco1
Copy link
Author

vco1 commented Sep 25, 2024 •
edited
Loading

It seems to connect now. But nothing gets advertised. MQTTExplorer has nothing for this device. It shows ble2mqtt as state online.
Loglevel set to debug. After initial failure to connect it seems to successfully connect afterwards (marked with ** at 11:12:10). This looks to be the relevant part of the log:

2024-09-25 11:12:09 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'Connected': <dbus_fast.signature.Variant ('b', False)>}, []]
2024-09-25 11:12:09 WARNING: Error while connecting to Oregon_ca80589369ab, [org.bluez.Error.Failed] Software caused connection abort BleakDBusError('org.bluez.Error.Failed', 'Software caused connection abort'), failure_count=2
2024-09-25 11:12:09 DEBUG: Sleep for 90 secs to reconnect to device=Oregon_ca80589369ab
2024-09-25 11:12:09 DEBUG: [Oregon_ca80589369ab] Check for lock
2024-09-25 11:12:09 DEBUG: Connecting to device @ CA:80:58:93:69:AB
2024-09-25 11:12:09 DEBUG: Connecting to BlueZ path /org/bluez/hci0/dev_CA_80_58_93_69_AB
2024-09-25 11:12:09 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0): ['org.bluez.Adapter1', {'Discovering': <dbus_fast.signature.Variant ('b', True)>}, []]
2024-09-25 11:12:09 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_E7_E7_DE_36_91_E8', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.Device1': {'Address': <dbus_fast.signature.Variant ('s', E7:E7:DE:36:91:E8)>, 'AddressType': <dbus_fast.signature.Variant ('s', random)>, 'Name': <dbus_fast.signature.Variant ('s', S14 105A LE)>, 'Alias': <dbus_fast.signature.Variant ('s', S14 105A LE)>, 'Paired': <dbus_fast.signature.Variant ('b', False)>, 'Trusted': <dbus_fast.signature.Variant ('b', False)>, 'Blocked': <dbus_fast.signature.Variant ('b', False)>, 'LegacyPairing': <dbus_fast.signature.Variant ('b', False)>, 'RSSI': <dbus_fast.signature.Variant ('n', -88)>, 'Connected': <dbus_fast.signature.Variant ('b', False)>, 'UUIDs': <dbus_fast.signature.Variant ('as', ['0000fe07-0000-1000-8000-00805f9b34fb'])>, 'Adapter': <dbus_fast.signature.Variant ('o', /org/bluez/hci0)>, 'ManufacturerData': <dbus_fast.signature.Variant ('a{qv}', {1447: <dbus_fast.signature.Variant ('ay', bytearray(b'\x98\xe7\x82\\\x93\xd43\xa3\xe9'))>})>, 'TxPower': <dbus_fast.signature.Variant ('n', 0)>, 'ServicesResolved': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_4B_D6_BD_4B_49_2B): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -36)>, 'TxPower': <dbus_fast.signature.Variant ('n', 12)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_6E_C0_7D_3E_AC_FA): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -36)>, 'TxPower': <dbus_fast.signature.Variant ('n', 12)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_5F_FC_FA_CD_C6_BC): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -41)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_E7_E7_DE_36_91_E8): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -86)>, 'ManufacturerData': <dbus_fast.signature.Variant ('a{qv}', {1447: <dbus_fast.signature.Variant ('ay', bytearray(b'\x98\xe7\x82\\\x93\xd43\xa3\xe9'))>})>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_5F_FC_FA_CD_C6_BC): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -39)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_6E_C0_7D_3E_AC_FA): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -39)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_4B_D6_BD_4B_49_2B): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -39)>}, []]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_45_78_D6_BD_53_1A', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.Device1': {'Address': <dbus_fast.signature.Variant ('s', 45:78:D6:BD:53:1A)>, 'AddressType': <dbus_fast.signature.Variant ('s', random)>, 'Alias': <dbus_fast.signature.Variant ('s', 45-78-D6-BD-53-1A)>, 'Paired': <dbus_fast.signature.Variant ('b', False)>, 'Trusted': <dbus_fast.signature.Variant ('b', False)>, 'Blocked': <dbus_fast.signature.Variant ('b', False)>, 'LegacyPairing': <dbus_fast.signature.Variant ('b', False)>, 'RSSI': <dbus_fast.signature.Variant ('n', -74)>, 'Connected': <dbus_fast.signature.Variant ('b', False)>, 'UUIDs': <dbus_fast.signature.Variant ('as', ['0000fef3-0000-1000-8000-00805f9b34fb'])>, 'Adapter': <dbus_fast.signature.Variant ('o', /org/bluez/hci0)>, 'ServiceData': <dbus_fast.signature.Variant ('a{sv}', {'0000fef3-0000-1000-8000-00805f9b34fb': <dbus_fast.signature.Variant ('ay', bytearray(b'J\x17#06SI\x112v\xd9\xd8\x9d*\r\xd9\xaaN\xddo\x14\xf2\xcc\xe1!\xfd-'))>})>, 'ServicesResolved': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -68)>}, []]
2024-09-25 11:12:10 DEBUG: active device seen: Oregon_ca80589369ab AdvertisementData(local_name='IDTW213R', service_uuids=['74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'], rssi=-68)
**2024-09-25 11:12:10 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'Connected': <dbus_fast.signature.Variant ('b', True)>}, []]**
2024-09-25 11:12:10 DEBUG: active device seen: Oregon_ca80589369ab AdvertisementData(local_name='IDTW213R', service_uuids=['74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'], rssi=-68)
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0008', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 00001801-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e01-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a/desc000c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e02-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d/desc000f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e03-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010/desc0012', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e04-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['notify'])>, 'NotifyAcquired': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013/desc0015', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e10-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016/desc0018', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e20-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019/desc001b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e2c-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c/desc001e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e14-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f/desc0021', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 0000180a-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a24-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023/desc0025', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a27-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026/desc0028', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a26-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029/desc002b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a29-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c/desc002e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 0000180f-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a19-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['read', 'notify'])>, 'NotifyAcquired': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030/desc0032', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'UUIDs': <dbus_fast.signature.Variant ('as', ['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000180f-0000-1000-8000-00805f9b34fb', '74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'])>, 'ServicesResolved': <dbus_fast.signature.Variant ('b', True)>}, []]
2024-09-25 11:12:11 DEBUG: active device seen: Oregon_ca80589369ab AdvertisementData(local_name='IDTW213R', service_uuids=['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000180f-0000-1000-8000-00805f9b34fb', '74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'], rssi=-68)
2024-09-25 11:12:11 INFO: Connected to CA:80:58:93:69:AB
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c): ['org.bluez.GattCharacteristic1', {'Value': <dbus_fast.signature.Variant ('ay', bytearray(b'IDT TECHNOLOGY LTD\x00\x00'))>}, []]
2024-09-25 11:12:11 DEBUG: Read Characteristic 00002a29-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c: bytearray(b'IDT TECHNOLOGY LTD\x00\x00')
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB', {'org.bluez.Battery1': {'Percentage': <dbus_fast.signature.Variant ('y', 100)>}}]
2024-09-25 11:12:11 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023): ['org.bluez.GattCharacteristic1', {'Value': <dbus_fast.signature.Variant ('ay', bytearray(b'IDT RAR213'))>}, []]
2024-09-25 11:12:11 DEBUG: Read Characteristic 00002a24-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023: bytearray(b'IDT RAR213')
2024-09-25 11:12:12 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029): ['org.bluez.GattCharacteristic1', {'Value': <dbus_fast.signature.Variant ('ay', bytearray(b'S001'))>}, []]
2024-09-25 11:12:12 DEBUG: Read Characteristic 00002a26-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029: bytearray(b'S001')
2024-09-25 11:12:12 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030): ['org.bluez.GattCharacteristic1', {'Value': <dbus_fast.signature.Variant ('ay', bytearray(b'd'))>}, []]
2024-09-25 11:12:12 DEBUG: Read Characteristic 00002a19-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030: bytearray(b'd')
2024-09-25 11:12:12 WARNING: Error while connecting to IDT_TECHNOLOGY_LTD_IDT_RAR213_ca80589369ab, 'BleakClient' object has no attribute 'characteristics' AttributeError("'BleakClient' object has no attribute 'characteristics'"), failure_count=3
2024-09-25 11:12:12 DEBUG: Disconnecting ({/org/bluez/hci0/dev_CA_80_58_93_69_AB})
2024-09-25 11:12:12 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB', ['org.bluez.Battery1']]

@devbis
Copy link
Owner

devbis commented Sep 25, 2024

Thanks! Added a fix. Run again, please.

@vco1
Copy link
Author

vco1 commented Sep 25, 2024 

2024-09-25 12:29:59 INFO: Connected to CA:80:58:93:69:AB
2024-09-25 12:29:59 WARNING: Error while connecting to IDT_TECHNOLOGY_LTD_IDTW213R_ca80589369ab, 'function' object has no attribute 'items' AttributeError("'function' object has no attribute 'items'"), failure_count=1

@devbis
Copy link
Owner

devbis commented Sep 25, 2024

fixed. Run again, please.

@vco1
Copy link
Author

vco1 commented Sep 25, 2024

Log level: INFO

2024-09-25 12:51:47 INFO: Connected to CA:80:58:93:69:AB
2024-09-25 12:51:53 ERROR: [IDT_TECHNOLOGY_LTD_IDTW213R_ca80589369ab] connection problem, attempts=1
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/locks.py", line 226, in wait
    await fut
asyncio.exceptions.CancelledError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/manager.py", line 490, in manage_device
    await handle_returned_tasks(*finished)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/tasks.py", line 79, in handle_returned_tasks
    await task_for_raise
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/devices/base.py", line 594, in handle
    return await self.handle_active(*args, **kwargs)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/devices/base.py", line 569, in handle_active
    await self.do_active_loop(publish_topic)
  File "/usr/local/lib/python3.9/dist-packages/ble2mqtt-0.2.2-py3.9.egg/ble2mqtt/devices/oregon_ht.py", line 264, in do_active_loop
    await aio.wait_for(self._state_ready.wait(), 5)
  File "/usr/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError

@devbis
Copy link
Owner

devbis commented Sep 25, 2024

Fixed.

@vco1
Copy link
Author

vco1 commented Sep 25, 2024

Amazed by how quickly you fix things. 👍

We're getting there...
MQTT Explorer now shows data. Although it's very limited. No temperature nor humidity.

0xca80589369ab = {"battery": 100, "linkquality": 56}
availability = online

Attached what I think is the relevant part of the log.

I do see only 2 publish statements. For battery and link quality.

2024-09-25 13:37:47 DEBUG: Publish config topic=homeassistant/sensor/b2m_ca80589369ab/battery/config: {"name": "battery_ca80589369ab", "unique_id": "battery_ca80589369ab", "device": {"identifiers": ["0xca80589369ab"], "name": "IDT_TECHNOLOGY_LTD_IDTW213R_ca80589369ab", "model": "IDTW213R", "manufacturer": "IDT TECHNOLOGY LTD", "sw_version": "S001"}, "availability_mode": "all", "availability": [{"topic": "ble2mqtt/bridge/state"}, {"topic": "ble2mqtt/0xca80589369ab/availability"}], "origin": {"name": "ble2mqtt", "sw_version": "0.2.2"}, "device_class": "battery", "unit_of_measurement": "%", "entity_category": "diagnostic", "state_topic": "ble2mqtt/0xca80589369ab", "value_template": "{{ value_json.battery }}"}
2024-09-25 13:37:47 DEBUG: Publish config topic=homeassistant/sensor/b2m_ca80589369ab/linkquality/config: {"name": "linkquality_ca80589369ab", "unique_id": "linkquality_ca80589369ab", "device": {"identifiers": ["0xca80589369ab"], "name": "IDT_TECHNOLOGY_LTD_IDTW213R_ca80589369ab", "model": "IDTW213R", "manufacturer": "IDT TECHNOLOGY LTD", "sw_version": "S001"}, "availability_mode": "all", "availability": [{"topic": "ble2mqtt/bridge/state"}, {"topic": "ble2mqtt/0xca80589369ab/availability"}], "origin": {"name": "ble2mqtt", "sw_version": "0.2.2"}, "icon": "mdi:signal", "unit_of_measurement": "lqi", "entity_category": "diagnostic", "state_topic": "ble2mqtt/0xca80589369ab", "value_template": "{{ value_json.linkquality }}"}

ble2mqtt.txt

@devbis
Copy link
Owner

devbis commented Sep 25, 2024 •
edited
Loading

Thanks for the log. Looks like it doesn't subscribe to the needed characteristics. I added more debug code and some workaround.
Can you please run it again and collect logs?

@vco1
Copy link
Author

vco1 commented Sep 25, 2024

I also noted that the example code on instructables.com contains this:

def _enableNotification(self):
		try:
			# Enable all notification or indication
			self.p.writeCharacteristic(0x000c, "\x02\x00")
			self.p.writeCharacteristic(0x000f, "\x02\x00")
			self.p.writeCharacteristic(0x0012, "\x02\x00")
			self.p.writeCharacteristic(0x0015, "\x01\x00")
			self.p.writeCharacteristic(0x0018, "\x02\x00")
			self.p.writeCharacteristic(0x001b, "\x02\x00")
			self.p.writeCharacteristic(0x001e, "\x02\x00")
			self.p.writeCharacteristic(0x0021, "\x02\x00")
			self.p.writeCharacteristic(0x0032, "\x01\x00")

Which seems to be missing from the ble2mqtt code. Could that cause the lack of data?

Is this the part from the log that you need?

Log:

2024-09-25 15:40:25 DEBUG: Connecting to device @ CA:80:58:93:69:AB
2024-09-25 15:40:25 DEBUG: Connecting to BlueZ path /org/bluez/hci0/dev_CA_80_58_93_69_AB
2024-09-25 15:40:25 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -84)>}, []]
2024-09-25 15:40:25 DEBUG: active device seen: Oregon_ca80589369ab AdvertisementData(local_name='IDTW213R', service_uuids=['74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'], rssi=-84)
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'Connected': <dbus_fast.signature.Variant ('b', True)>}, []]
2024-09-25 15:40:26 DEBUG: active device seen: Oregon_ca80589369ab AdvertisementData(local_name='IDTW213R', service_uuids=['74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'], rssi=-84)
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_71_3B_E0_8D_41_83): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -39)>}, []]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_5B_04_76_AE_AA_A4): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -83)>}, []]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_E7_E7_DE_36_91_E8): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -89)>, 'ManufacturerData': <dbus_fast.signature.Variant ('a{qv}', {1447: <dbus_fast.signature.Variant ('ay', bytearray(b'\x98\xe7\x82\\\x93\xd43\xa3\xe9'))>})>}, []]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_4A_D1_A8_3C_71_C9): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -36)>}, []]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_69_FD_60_EF_E1_3F): ['org.bluez.Device1', {'RSSI': <dbus_fast.signature.Variant ('n', -41)>}, []]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0008', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 00001801-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e01-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a/desc000c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000a)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e02-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d/desc000f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char000d)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e03-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010/desc0012', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0010)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e04-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['notify'])>, 'NotifyAcquired': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013/desc0015', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0013)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e10-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016/desc0018', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0016)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e20-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019/desc001b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char0019)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e2c-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['write', 'indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c/desc001e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001c)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 74e78e14-c6a4-11e2-b7a9-0002a5d5c51b)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['indicate'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f/desc0021', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0009/char001f)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 0000180a-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a24-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023/desc0025', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0023)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a27-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026/desc0028', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0026)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a26-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029/desc002b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char0029)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a29-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Flags': <dbus_fast.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c/desc002e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002904-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service0022/char002c)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_fast.signature.Variant ('s', 0000180f-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB)>, 'Primary': <dbus_fast.signature.Variant ('b', True)>, 'Includes': <dbus_fast.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_fast.signature.Variant ('s', 00002a19-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>, 'Notifying': <dbus_fast.signature.Variant ('b', False)>, 'Flags': <dbus_fast.signature.Variant ('as', ['read', 'notify'])>, 'NotifyAcquired': <dbus_fast.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030/desc0032', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_fast.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_fast.signature.Variant ('o', /org/bluez/hci0/dev_CA_80_58_93_69_AB/service002f/char0030)>, 'Value': <dbus_fast.signature.Variant ('ay', bytearray(b''))>}, 'org.freedesktop.DBus.Properties': {}}]
2024-09-25 15:40:26 DEBUG: received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_CA_80_58_93_69_AB): ['org.bluez.Device1', {'UUIDs': <dbus_fast.signature.Variant ('as', ['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000180f-0000-1000-8000-00805f9b34fb', '74e7fe00-c6a4-11e2-b7a9-0002a5d5c51b'])>, 'ServicesResolved': <dbus_fast.signature.Variant ('b', True)>}, []]
2024-09-25 15:40:26 INFO: Connected to CA:80:58:93:69:AB

@devbis
Copy link
Owner

devbis commented Sep 26, 2024

It is subscribing to notifications, but via a high-level methods.
I need more logs, after "connected" string.

@vco1
Copy link
Author

vco1 commented Sep 27, 2024

This is all the logging for about the first minute after start of ble2mqtt.
ble2mqtt_20240927.log

@devbis
Copy link
Owner

devbis commented Sep 27, 2024

Thanks, that's what I needed. For some reason the device is not sending data for 5 seconds.
I additionally subscribed to all possible characteristics in the device.
Can you please collect logs like you did in the comment above?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
new device
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@devbis @vco1