[Bug]: AC180P \ EB3A Pooling timeout

DmytroPotapov commented 4 months ago

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 commented 2 months ago

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 commented 3 weeks ago

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

Patrick762 / hassio-bluetti-bt