[Bug]: AC180P \ EB3A Pooling timeout

[DmytroPotapov]

What happened?

I use HA Supervised 2024.7.3.

After connecting the device to an extension, it shows as 'Unavailable' after 1-2 minutes and does not return to the expected state.

What version of our software are you running?

0.1.3

What device are you seeing the problem on?

AC180P and EB3A

What bluetooth connection are you using?

USB dongle

Have you changed the integration settings?

Yes (Please write down the changed settings in the next step)

Integration settings (if you changed them)

Persistent connection - does not sosolve a problem
Pooling interval and timeout -  does not sosolve a problem

Relevant log output

2024-07-23 09:52:32.214 ERROR (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Bleak error: failed to discover services, device disconnected
2024-07-23 09:53:02.164 WARNING (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Polling timed out. Trying again later
2024-07-23 09:53:32.163 WARNING (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Polling timed out. Trying again later

[raziel83257]

I am experiencing the same issue described in this thread. My ESP32-C3 Supermini Bluetooth proxy works correctly and collects data for a few minutes, but then all the data becomes unavailable. The issue persists even after restarting the device or Home Assistant (in this last case the integration works for few minutes and then stops again).

Bluetti integration version 0.1.4
EB3A battery
ARM Version
2053.08
DSP Version
2052.11

I'm attaching the logs for further investigation.

2024-09-04 14:03:19.871 INFO (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Reading data
2024-09-04 14:03:19.872 INFO (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Polling commands: 10-49,77-86,3006-3008,3034-3034,3060-3066
2024-09-04 14:03:19.872 INFO (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Pack comands:
2024-09-04 14:03:19.872 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Requesting ReadHoldingRegisters(starting_address=10, quantity=40)
2024-09-04 14:03:20.114 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Got 85 bytes
2024-09-04 14:03:20.114 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Raw data: b'EB3A\x00\x00\x00\x00\x00\x00\x00\x00\x03\xfbM3Z\x15\x02\x05\x00\x00\x00\x00\x00\x00!\xfc\x00\x03!\x9b\x00\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x8c\x00\x8c\x00\x00\x00\x00\x00#\x00\x00\x00d\x00\x00\x00\x01\x00\x00\x00\x01\x00\x01\x00\x01'
2024-09-04 14:03:20.114 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Parsed data: {'device_type': 'EB3A', 'serial_number': 2222009437491, 'arm_version': Decimal('2053.08'), 'dsp_version': Decimal('2052.11'), 'dc_input_power': 0, 'ac_input_power': 140, 'ac_output_power': 140, 'dc_output_power': 0, 'power_generation': Decimal('3.5'), 'total_battery_percent': 100, 'ac_output_on': True, 'dc_output_on': True}
2024-09-04 14:03:20.114 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Requesting ReadHoldingRegisters(starting_address=77, quantity=10)
2024-09-04 14:03:20.319 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Got 25 bytes
2024-09-04 14:03:20.319 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Raw data: b'\t\x19\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
2024-09-04 14:03:20.319 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Parsed data: {'ac_input_voltage': Decimal('232.9'), 'internal_dc_input_voltage': 0}
2024-09-04 14:03:20.319 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Requesting ReadHoldingRegisters(starting_address=3006, quantity=3)
2024-09-04 14:03:20.474 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Got 11 bytes
2024-09-04 14:03:20.474 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Raw data: b'\x00\x00\x00\x01\x00\x01'
2024-09-04 14:03:20.474 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Parsed data: {'pack_num': 0, 'ac_output_on_switch': True, 'dc_output_on_switch': True}
2024-09-04 14:03:20.474 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Requesting ReadHoldingRegisters(starting_address=3034, quantity=1)
2024-09-04 14:03:20.670 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Got 7 bytes
2024-09-04 14:03:20.670 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Raw data: b'\x00\x04'
2024-09-04 14:03:20.670 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Parsed data: {'led_mode': <LedMode.OFF: 4>}
2024-09-04 14:03:20.670 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Requesting ReadHoldingRegisters(starting_address=3060, quantity=7)
2024-09-04 14:03:20.838 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Got 19 bytes
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Raw data: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00'
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.bluetti_bt_lib.bluetooth.device_reader] Parsed data: {'power_off': False, 'eco_on': False, 'eco_shutdown': <EcoShutdown.ONE_HOUR: 1>, 'charging_mode': <ChargingMode.STANDARD: 0>, 'power_lifting_on': False}
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.coordinator] Finished fetching Bluetti polling coordinator data in 0.967 seconds (success: True)
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.binary_sensor] Updating state of eb3a22XXXXXXXXXXac_output
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.binary_sensor] Updating state of eb3a22XXXXXXXXXXdc_output
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXdc_input_power
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXac_input_power
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXac_output_power
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXdc_output_power
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXdsp_version
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXarm_version
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXtotal_power_generation
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXtotal_battery_percent
2024-09-04 14:03:20.839 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXac_input_voltage
2024-09-04 14:03:20.840 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXinternal_dc_input_voltage
2024-09-04 14:03:20.840 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXled_mode
2024-09-04 14:03:20.840 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXeco_shutdown
2024-09-04 14:03:20.840 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Updating state of eb3a22XXXXXXXXXXcharging_mode
2024-09-04 14:03:25.872 WARNING (MainThread) [custom_components.bluetti_bt.coordinator] Device not connected
2024-09-04 14:03:25.872 INFO (MainThread) [custom_components.bluetti_bt.coordinator] Fetching Bluetti polling coordinator data recovered
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.coordinator] Finished fetching Bluetti polling coordinator data in 0.000 seconds (success: True)
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.binary_sensor] Data from coordinator is None
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.binary_sensor] Data from coordinator is None
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.872 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:25.873 DEBUG (MainThread) [custom_components.bluetti_bt.sensor] Data from coordinator is None
2024-09-04 14:03:30.872 WARNING (MainThread) [custom_components.bluetti_bt.coordinator] Device not connected
2024-09-04 14:03:30.872 INFO (MainThread) [custom_components.bluetti_bt.coordinator] Fetching Bluetti polling coordinator data recovered
2024-09-04 14:03:30.872 DEBUG (MainThread) [custom_components.bluetti_bt.coordinator] Finished fetching Bluetti polling coordinator data in 0.000 seconds (success: True)

[DmytroPotapov]

I confirm. I also tried ESP Home Bluetooth Proxy, the integration behavior did not change