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

Liontron Batteries #141

Closed
4 tasks done
jassi0001 opened this issue Jan 4, 2025 · 12 comments · May be fixed by #146
Closed
4 tasks done

Liontron Batteries #141

jassi0001 opened this issue Jan 4, 2025 · 12 comments · May be fixed by #146
Assignees
@patman15
Labels
enhancement New feature or request

Comments

@jassi0001
Copy link

jassi0001 commented Jan 4, 2025 •
edited
Loading

Checklist

  • I have filled out the template to the best of my ability.
  • I have understood that this integration is only about battery management systems with Bluetooth Low Energy support.
  • This only contains 1 feature request (if you have multiple feature requests, open one feature request for each feature request).
  • This issue is not a duplicate of any previous feature requests.

Please describe the enhancement you would like to have.

I have two Liontron Batteries (). The ESPHome Project "github://syssi/esphome-jbd-bms@main" integrated it together with me.
device_description: "Monitor and control a Xiaoxiang Battery Management System (JBD-BMS) via BLE"

The ESPHome communication is working as expected.
Maybe you can implement it an i can test?
in the other Project there where timing issues. after changing that, the communication worked....

Additional context

github://syssi/esphome-jbd-bms@main
Feature Request at syssi: syssi/esphome-jbd-bms#81
Implementationat syssi: syssi/esphome-jbd-bms#84
https://liontron.com/
https://liontron.com/download/german/LISMART1240LX.pdf and
https://liontron.com/download/german/LISMART1255LX.pdf
unfortunatly i do not have a ble protocol.
@jassi0001 jassi0001 added the enhancement New feature or request label Jan 4, 2025
@patman15
Copy link
Owner

patman15 commented Jan 4, 2025

If it is a JBD then the protocol is already there, so I guess it is only the detection that does not work. Can you provide the output of bluetoothctl info <MAC>? Could be a simple update.

@jassi0001
Copy link
Author

jassi0001 commented Jan 4, 2025 •
edited
Loading

this should help:
[17:32:28][D][esp32_ble_client:110]: [0] [70:3E:97:D1:BE:20] Found device
[17:32:28][D][esp32_ble_tracker:669]: Found device 70:3E:97:D1:BE:20 RSSI=-92
[17:32:28][D][esp32_ble_tracker:690]: Address Type: PUBLIC
[17:32:28][D][esp32_ble_tracker:692]: Name: '0223400011'
[17:32:28][D][esp32_ble_tracker:695]: TX Power: 2
[17:32:28][D][esp32_ble_tracker:219]: Pausing scan to make connection...
[17:32:28][D][esp32_ble_tracker:219]: Pausing scan to make connection...
[...]
[17:32:29][D][esp32_ble_client:110]: [0] [70:3E:97:D1:BE:20] ESP_GATTC_SEARCH_CMPL_EVT
[17:32:29][V][esp32_ble_client:223]: [0] [70:3E:97:D1:BE:20] Service UUID: 0x1800
[17:32:29][V][esp32_ble_client:225]: [0] [70:3E:97:D1:BE:20] start_handle: 0x1 end_handle: 0x7
[17:32:29][V][esp32_ble_client:223]: [0] [70:3E:97:D1:BE:20] Service UUID: 0x1801
[17:32:29][V][esp32_ble_client:225]: [0] [70:3E:97:D1:BE:20] start_handle: 0x8 end_handle: 0xb
[17:32:29][V][esp32_ble_client:223]: [0] [70:3E:97:D1:BE:20] Service UUID: 0x180A
[17:32:29][V][esp32_ble_client:225]: [0] [70:3E:97:D1:BE:20] start_handle: 0xc end_handle: 0xe
[17:32:29][V][esp32_ble_client:223]: [0] [70:3E:97:D1:BE:20] Service UUID: 0xFF00
[17:32:29][V][esp32_ble_client:225]: [0] [70:3E:97:D1:BE:20] start_handle: 0xf end_handle: 0x16
[17:32:29][V][esp32_ble_client:223]: [0] [70:3E:97:D1:BE:20] Service UUID: 00010203-0405-0607-0809-0A0B0C0D1912
[17:32:29][V][esp32_ble_client:225]: [0] [70:3E:97:D1:BE:20] start_handle: 0x17 end_handle: 0x1b
[17:32:29][I][esp32_ble_client:227]: [0] [70:3E:97:D1:BE:20] Connected
[17:32:29][V][esp32_ble_client:069]: [0] [70:3E:97:D1:BE:20] characteristic 0xFF01, handle 0x11, properties 0x12
[17:32:29][V][esp32_ble_client:069]: [0] [70:3E:97:D1:BE:20] characteristic 0xFF02, handle 0x15, properties 0x6
[17:32:29][V][esp32_ble_client:121]: [0] [70:3E:97:D1:BE:20] gattc_event_handler: event=18 gattc_if=3
[17:32:29][D][esp32_ble_client:188]: [0] [70:3E:97:D1:BE:20] cfg_mtu status 0, mtu 128
[17:32:29][V][esp32_ble_client:121]: [0] [70:3E:97:D1:BE:20] gattc_event_handler: event=38 gattc_if=3
[17:32:29][D][esp32_ble_client:110]: [0] [70:3E:97:D1:BE:20] ESP_GATTC_REG_FOR_NOTIFY_EVT

bluetoothctl info

First (40Ah)

Device 70:3E:97:D1:BE:20 (public)
Name: 0223400011
Alias: 0223400011
Paired: no
Bonded: no
Trusted: no
Blocked: no
Connected: no
LegacyPairing: no
UUID: Unknown (0000ff00-0000-1000-8000-00805f9b34fb)
ManufacturerData.Key: 0x3e70 (15984)
ManufacturerData.Value:
97 d1 be 20 ...
RSSI: 0xffffffc0 (-64)
TxPower: 0x003f (63)
AdvertisingFlags:
06 .

Second (55Ah)

Device 70:3E:97:D1:C1:8C (public)
Name: 0923550208
Alias: 0923550208
Paired: no
Bonded: no
Trusted: no
Blocked: no
Connected: no
LegacyPairing: no
UUID: Unknown (0000ff00-0000-1000-8000-00805f9b34fb)
ManufacturerData.Key: 0x3e70 (15984)
ManufacturerData.Value:
97 d1 c1 8c ....
RSSI: 0xffffffc8 (-56)
TxPower: 0x003f (63)
AdvertisingFlags:
06

Einrichtungsfehler, wird erneut versucht: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)

@patman15
Copy link
Owner

patman15 commented Jan 4, 2025

Einrichtungsfehler, wird erneut versucht: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)

Not sure how you reached that state, because that should not happen ...

Can you try the linked branch? That should work. If not, please provide a debug log! Thanks.

@patman15 patman15 self-assigned this Jan 4, 2025
@jassi0001
Copy link
Author

jassi0001 commented Jan 5, 2025 •
edited by patman15
Loading

Here are the Debug Logs:

2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JFJKNJ65RKV2YP17MDRD3227 version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -58, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-58), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 971911.577254009, 'tx_power': 63}
2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-05 19:54:54.684 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-05 19:54:54.684 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-05 19:54:55.475 DEBUG (MainThread) [custom_components.bms_ble] Bluetooth device detected: <BluetoothServiceInfoBleak name=0223400011 address=70:3E:97:D1:BE:20 rssi=-127 manufacturer_data={15984: b'\x97\xd1\xbe '} service_data={} service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'] source=hci0 connectable=True time=971509.582638687 tx_power=None>
2025-01-05 19:54:55.476 DEBUG (MainThread) [custom_components.bms_ble] Bluetooth device detected: <BluetoothServiceInfoBleak name=0923550208 address=70:3E:97:D1:C1:8C rssi=-127 manufacturer_data={15984: b'\x97\xd1\xc1\x8c'} service_data={} service_uuids=['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000ff00-0000-1000-8000-00805f9b34fb', '00010203-0405-0607-0809-0a0b0c0d1912'] source=hci0 connectable=True time=971509.582638687 tx_power=None>
2025-01-05 19:55:14.686 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-05 19:55:14.686 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 2.8953072614967823e-05 seconds to disconnect
2025-01-05 19:55:14.937 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2
2025-01-05 19:55:34.939 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 2, last rssi: None)
2025-01-05 19:55:34.939 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 5.365500692278147e-05 seconds to disconnect
2025-01-05 19:55:35.190 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 3
2025-01-05 19:55:55.192 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 3, last rssi: None)
2025-01-05 19:55:55.192 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.07250302284956e-05 seconds to disconnect
2025-01-05 19:55:55.443 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 4
2025-01-05 19:55:59.832 DEBUG (MainThread) [custom_components.bms_ble] Bluetooth device detected: <BluetoothServiceInfoBleak name=0923550208 address=70:3E:97:D1:C1:8C rssi=-54 manufacturer_data={15984: b'\x97\xd1\xc1\x8c'} service_data={} service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'] source=BC:2B:02:77:41:62 connectable=True time=971976.286673571 tx_power=63>
2025-01-05 19:55:59.855 DEBUG (MainThread) [custom_components.bms_ble] Device 0923550208 (70:3e:97:d1:c1:8c) detected as 'Jiabaida Smart BMS'
2025-01-05 19:55:59.855 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0923550208
2025-01-05 19:56:15.445 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 4, last rssi: None)
2025-01-05 19:56:15.445 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.1574047170579433e-05 seconds to disconnect
2025-01-05 19:56:15.696 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)
2025-01-05 19:56:15.696 DEBUG (MainThread) [custom_components.bms_ble] Finished fetching 0223400011 data in 81.012 seconds (success: False)
2025-01-05 19:56:15.696 DEBUG (MainThread) [custom_components.bms_ble] Shutting down BMS device (0223400011)
2025-01-05 19:56:15.697 DEBUG (MainThread) [custom_components.bms_ble] Bluetooth device detected: <BluetoothServiceInfoBleak name=0223400011 address=70:3E:97:D1:BE:20 rssi=-58 manufacturer_data={15984: b'\x97\xd1\xbe '} service_data={} service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'] source=BC:2B:02:77:41:62 connectable=True time=971992.852780938 tx_power=63>
2025-01-05 19:56:15.697 DEBUG (MainThread) [custom_components.bms_ble] Device 0223400011 (70:3e:97:d1:be:20) detected as 'Jiabaida Smart BMS'
2025-01-05 19:56:15.697 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0223400011
2025-01-05 19:56:44.791 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0223400011
2025-01-05 19:56:47.166 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0223400011
2025-01-05 19:56:47.166 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGVX7BHYZB03CY5H48THGMY2 version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-05 19:56:47.166 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-05 19:56:47.166 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-05 19:56:47.166 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -58, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-58), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 972021.618967331, 'tx_power': 63}
2025-01-05 19:56:47.167 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-05 19:56:47.167 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-05 19:56:47.167 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-05 19:57:07.169 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-05 19:57:07.169 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 4.9202004447579384e-05 seconds to disconnect
2025-01-05 19:57:07.421 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2

@patman15
Copy link
Owner

patman15 commented Jan 5, 2025

Seems like BT has troubles connecting, so far I could not spot an issue with the integration. Can you please

  • make sure nothing else is connected to the batteryies, e.g. ESPHome
  • wait at least 5 minutes
  • attach the log file of those 5 minutes?

@jassi0001
Copy link
Author

jassi0001 commented Jan 6, 2025 •
edited by patman15
Loading

Hi, the problem seems to be the same as in the ESPHome Project. There where Timing Problems while getting data, which could be solved there... i don't know, if you find it in the code.... i linked the artikel in my first post....
When i connect the ESPHome installation, it connects just as i start the connection. Xiaoxing App on Iphone as well (to both Batteries)

2025-01-06 07:27:10.106 DEBUG (MainThread) [custom_components.bms_ble] Bluetooth device detected: <BluetoothServiceInfoBleak name=0923550208 address=70:3E:97:D1:C1:8C rssi=-52 manufacturer_data={15984: b'\x97\xd1\xc1\x8c'} service_data={} service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'] source=BC:2B:02:77:41:62 connectable=True time=1013447.31705354 tx_power=63>
2025-01-06 07:27:10.106 DEBUG (MainThread) [custom_components.bms_ble] Device 0923550208 (70:3e:97:d1:c1:8c) detected as 'Jiabaida Smart BMS'
2025-01-06 07:27:10.107 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0923550208
2025-01-06 07:28:06.433 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0223400011
2025-01-06 07:28:08.648 DEBUG (MainThread) [custom_components.bms_ble] confirm step for 0223400011
2025-01-06 07:28:08.648 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGX4S8T88JXGVGZ3715N38NN version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-06 07:28:08.648 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-06 07:28:08.648 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-06 07:28:08.648 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -60, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-60), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 1013500.902369023, 'tx_power': 63}
2025-01-06 07:28:08.649 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-06 07:28:08.649 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-06 07:28:08.649 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-06 07:28:28.650 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-06 07:28:28.650 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 2.816098276525736e-05 seconds to disconnect
2025-01-06 07:28:28.902 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2
2025-01-06 07:28:48.903 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 2, last rssi: None)
2025-01-06 07:28:48.903 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 4.1215913370251656e-05 seconds to disconnect
2025-01-06 07:28:49.155 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 3
2025-01-06 07:29:09.157 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 3, last rssi: None)
2025-01-06 07:29:09.157 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 2.8908951207995415e-05 seconds to disconnect
2025-01-06 07:29:09.408 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 4
2025-01-06 07:29:29.410 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 4, last rssi: None)
2025-01-06 07:29:29.410 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 4.640000406652689e-05 seconds to disconnect
2025-01-06 07:29:29.661 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)
2025-01-06 07:29:29.661 DEBUG (MainThread) [custom_components.bms_ble] Finished fetching 0223400011 data in 81.013 seconds (success: False)
2025-01-06 07:29:29.662 DEBUG (MainThread) [custom_components.bms_ble] Shutting down BMS device (0223400011)
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGX4S8T88JXGVGZ3715N38NN version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -60, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-60), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 1013582.016851632, 'tx_power': 63}
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-06 07:29:35.050 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-06 07:29:35.050 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-06 07:29:55.052 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-06 07:29:55.052 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.055599518120289e-05 seconds to disconnect
2025-01-06 07:29:55.303 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2
2025-01-06 07:30:15.306 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 2, last rssi: None)
2025-01-06 07:30:15.306 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.4303986467421055e-05 seconds to disconnect
2025-01-06 07:30:15.557 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 3
2025-01-06 07:30:35.558 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 3, last rssi: None)
2025-01-06 07:30:35.559 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.2853917218744755e-05 seconds to disconnect
2025-01-06 07:30:35.809 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 4
2025-01-06 07:30:55.811 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 4, last rssi: None)
2025-01-06 07:30:55.812 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.039790317416191e-05 seconds to disconnect
2025-01-06 07:30:56.064 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)
2025-01-06 07:30:56.064 DEBUG (MainThread) [custom_components.bms_ble] Finished fetching 0223400011 data in 81.014 seconds (success: False)
2025-01-06 07:30:56.064 DEBUG (MainThread) [custom_components.bms_ble] Shutting down BMS device (0223400011)
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGX4S8T88JXGVGZ3715N38NN version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -60, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-60), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 1013674.084405753, 'tx_power': 63}
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-06 07:31:06.143 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-06 07:31:06.143 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-06 07:31:26.145 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-06 07:31:26.145 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.07129230350256e-05 seconds to disconnect
2025-01-06 07:31:26.396 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2
2025-01-06 07:31:46.398 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 2, last rssi: None)
2025-01-06 07:31:46.399 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 4.45069745182991e-05 seconds to disconnect
2025-01-06 07:31:46.649 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 3
2025-01-06 07:32:06.652 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 3, last rssi: None)
2025-01-06 07:32:06.652 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 5.026394501328468e-05 seconds to disconnect
2025-01-06 07:32:06.904 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 4
2025-01-06 07:32:26.906 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 4, last rssi: None)
2025-01-06 07:32:26.906 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Device was removed from bus at /org/bluez/hci0/dev_70_3E_97_D1_BE_20, waiting 0.25 for it to re-appear: (<class 'bleak.exc.BleakError'>) device 'dev_70_3E_97_D1_BE_20' not found
2025-01-06 07:32:26.906 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Device did not re-appear on bus after 0.25 seconds
2025-01-06 07:32:26.906 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)
2025-01-06 07:32:26.907 DEBUG (MainThread) [custom_components.bms_ble] Finished fetching 0223400011 data in 80.764 seconds (success: False)
2025-01-06 07:32:26.907 DEBUG (MainThread) [custom_components.bms_ble] Shutting down BMS device (0223400011)
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGX4S8T88JXGVGZ3715N38NN version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -60, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-60), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 1013782.630066288, 'tx_power': 63}
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-06 07:32:47.006 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-06 07:32:47.007 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-06 07:33:07.009 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-06 07:33:07.009 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.4585013054311275e-05 seconds to disconnect
2025-01-06 07:33:07.260 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2
2025-01-06 07:33:27.263 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 2, last rssi: None)
2025-01-06 07:33:27.263 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.2479059882462025e-05 seconds to disconnect
2025-01-06 07:33:27.514 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 3
2025-01-06 07:33:47.516 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 3, last rssi: None)
2025-01-06 07:33:47.517 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.524695057421923e-05 seconds to disconnect
2025-01-06 07:33:47.768 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 4
2025-01-06 07:34:07.769 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 4, last rssi: None)
2025-01-06 07:34:07.770 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Device was removed from bus at /org/bluez/hci0/dev_70_3E_97_D1_BE_20, waiting 0.25 for it to re-appear: (<class 'bleak.exc.BleakError'>) device 'dev_70_3E_97_D1_BE_20' not found
2025-01-06 07:34:07.770 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Device did not re-appear on bus after 0.25 seconds
2025-01-06 07:34:07.770 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: device communicating failed: 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Failed to connect after 4 attempt(s): TimeoutError (BleakNotFoundError)
2025-01-06 07:34:07.770 DEBUG (MainThread) [custom_components.bms_ble] Finished fetching 0223400011 data in 80.763 seconds (success: False)
2025-01-06 07:34:07.770 DEBUG (MainThread) [custom_components.bms_ble] Shutting down BMS device (0223400011)
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble] Setup of <ConfigEntry entry_id=01JGX4S8T88JXGVGZ3715N38NN version=1 domain=bms_ble title=0223400011 state=ConfigEntryState.SETUP_IN_PROGRESS unique_id=70:3E:97:D1:BE:20>
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: initializing Jiabaida Smart BMS, BT address: 70:3E:97:D1:BE:20
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble] Initializing coordinator for 0223400011 (70:3E:97:D1:BE:20) as Jiabaida Smart BMS
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: advertisement: {'name': '0223400011', 'address': '70:3E:97:D1:BE:20', 'rssi': -56, 'manufacturer_data': {15984: b'\x97\xd1\xbe '}, 'service_data': {}, 'service_uuids': ['0000ff00-0000-1000-8000-00805f9b34fb'], 'source': 'BC:2B:02:77:41:62', 'advertisement': AdvertisementData(local_name='0223400011', manufacturer_data={15984: b'\x97\xd1\xbe '}, service_uuids=['0000ff00-0000-1000-8000-00805f9b34fb'], tx_power=63, rssi=-56), 'device': BLEDevice(70:3E:97:D1:BE:20, 0223400011), 'connectable': True, 'time': 1013898.416777907, 'tx_power': 63}
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble] 0223400011: BMS data update
2025-01-06 07:34:48.144 DEBUG (MainThread) [custom_components.bms_ble.plugins.jbd_bms] 0223400011[BE:20]: connecting BMS
2025-01-06 07:34:48.144 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 1
2025-01-06 07:35:08.147 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Timed out trying to connect (attempt: 1, last rssi: None)
2025-01-06 07:35:08.147 DEBUG (MainThread) [bleak_retry_connector.bluez] 0223400011 - 70:3E:97:D1:BE:20: Waited 3.4476048313081264e-05 seconds to disconnect
2025-01-06 07:35:08.398 DEBUG (MainThread) [bleak_retry_connector] 70:3E:97:D1:BE:20 - 70:3E:97:D1:BE:20: Connection attempt: 2

@patman15
Copy link
Owner

patman15 commented Jan 6, 2025 •
edited
Loading

Hi, the problem seems to be the same as in the ESPHome Project. There where Timing Problems while getting data, which could be solved there... i don't know, if you find it in the code.... i linked the artikel in my first post....

I have had a look at the issue, but I can't see the relation (yet). The logs show that HA is unable to even make a connection. The integration doesn't reach the state to send a single command. For me it looks like either

  • the battery requires pairing (bluetoothctl pair <MAC>),
  • you still have another device connected to it, or
  • there is something really strange that I'm not aware of.

Unfortunately, I do not see much because even the connect command fails.
Which HA version and what BT adapter are you using? Did you check that the device is visible using bluetoothctl devices? Can you connect using bluetoothctl connect <MAC> ?

@jassi0001
Copy link
Author

hci0 70:3E:97:D1:C1:8C type LE Public connect failed (status 0x08, Timeout)
Pairing is not neccesary .... i do not know, what excactly the timing Problem was and how it was solved, but i can ask....
There is no other device connected and when i try with the iphone app, connect ist established prompt... Same with ESPHome....

@jassi0001 jassi0001 mentioned this issue Jan 6, 2025
Closed
@patman15
Copy link
Owner

patman15 commented Jan 8, 2025 •
edited
Loading

@jassi0001 I talked to @syssi yesterday. I cannot see any relation between the changes that were done and the current issue. Given the fact that you cannot connect using bluetoothctl strongly hints at an issue with the Bluetooth stack.
Can you please give more details:

  • bluetoothctl devices to see how many others are around
  • BT adapter you are using
  • Home Assistant version
  • Home Assistant installation type
  • Are you using any other BT devices with Home Assistant that work? If you use a proxy which your logs hint to, please verify that you do not exceed the 3 devices/proxy limit!

@jassi0001
Copy link
Author

bluetoothctl devices to see how many others are around
BT adapter you are using Realtec mapped through proxmox to a HA virtual installation.
Home Assistant version : 2025.1
Home Assistant installation type . Proxmox virtual HAOS
Are you using any other BT devices with Home Assistant that work? you're right . i activated it only for battery monitor. So at the moment no other connected and working.
bluetoothctl devices shows up 15 Devices arround... but i can not connect to one of them. all timeout. I will check here...

as you said, it is a problem with the adapter or the stack....

@jassi0001
Copy link
Author

problem seems to be inside the HAOS... connection from Proxmox works.... i Think you can close here and i have to check in my installation

@patman15
Copy link
Owner

patman15 commented Jan 9, 2025 •
edited
Loading

Closing is up to the reporter, unless I fixed something. 😄 Close at your needs, I'm happy to help you with further input, if you have questions. If you haven't done so, I recommend to "pass-through" the whole USB device to the VM. At least for me the setup works.
You also might want to take a look at the high performance adapters recommended by HA.

@jassi0001 jassi0001 closed this as not planned Won't fix, can't repro, duplicate, stale Jan 9, 2025
@patman15 patman15 linked a pull request Jan 10, 2025 that will close this issue
Add Liontron battery detection #146
Draft
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@patman15 patman15

Labels
enhancement New feature or request
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Add Liontron battery detection patman15/BMS_BLE-HA
2 participants
@patman15 @jassi0001