[Bug]: failed to setup

[yousaf465]

Describe the issue

since two days of facing this issue. how to download the debug logs?

Bescribe your Huawei Solar Setup

Inverter Type:
Inverter Firmware version:
SDongle present: yes / no
Power meter present: three phase / single phase / no
Battery: LG RESU xxkWh / LUNA2000 xxkWh / None
Battery Firmware version:

How do you connect to the inverter?

Via the SUN2000-<serial_no> WiFi

Upload your Diagnostics File

Drag & Drop your Diagnostics File here.
home-assistant_huawei_solar_2023-07-20T05-38-52.296Z.log

Upload your relevant debug logs

2023-07-20 04:22:55.333 ERROR (MainThread) [huawei_solar.huawei_solar] Timeout while waiting for connection. Reconnecting...
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/asyncio/tasks.py", line 490, in wait_for
    return fut.result()
           ^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/locks.py", line 213, in wait
    await fut
asyncio.exceptions.CancelledError

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 141, in _communication_lock
    await asyncio.wait_for(self._client.connected_event.wait(), WAIT_FOR_CONNECTION_TIMEOUT)
  File "/usr/local/lib/python3.11/asyncio/tasks.py", line 492, in wait_for
    raise exceptions.TimeoutError() from exc
TimeoutError
2023-07-20 04:22:55.452 ERROR (MainThread) [huawei_solar.huawei_solar] Aborting client creation due to error.
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/asyncio/tasks.py", line 490, in wait_for
    return fut.result()
           ^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/locks.py", line 213, in wait
    await fut
asyncio.exceptions.CancelledError

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 178, in create
    await huawei_solar._initialize()
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 107, in _initialize
    self.time_zone = (await self.get(rn.TIME_ZONE)).value
                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 241, in get
    return (await self.get_multiple([name], slave))[0]
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 276, in get_multiple
    response = await self._read_registers(registers[0].register, total_length, slave)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 396, in _read_registers
    async with self._communication_lock():
  File "/usr/local/lib/python3.11/contextlib.py", line 204, in __aenter__
    return await anext(self.gen)
           ^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 145, in _communication_lock
    raise err
  File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 141, in _communication_lock
    await asyncio.wait_for(self._client.connected_event.wait(), WAIT_FOR_CONNECTION_TIMEOUT)
  File "/usr/local/lib/python3.11/asyncio/tasks.py", line 492, in wait_for
    raise exceptions.TimeoutError() from exc
TimeoutError
2023-07-20 04:22:55.514 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry SUN2000-10KTL-M1

Please confirm the following:

• The problem is still present in the latest release of this integration.
• I did not find an existing issue describing this problem.
• I did upload the diagnostics-file that I could retrieve from the 'Devices & Services Page'
• I did upload the relevant debug logs (via 'Enable Debug Logging'-feature or by manually configuring HA logging)

[danise76]

I have the same problem after updating from 1.25 to 1.26
I have 2 inverters connected with Serial Modbus. 1 inverter has a Power meter and the 2nd one only has the inverter.
Is added with "slave IDs" "1, 2" in the configuration.

I tried removing the component, reloading, etc. Nothing works, same errors as you indicate in the log.

If I add it with ONLY 1 slave, it works, but i only see one of the inverter (Either the main one + power meter, or the 2nd one).

For now i'm with 1.25 still

[wlcrs]

pyModbus 3.4.0 has been released 2 days ago, mentioning some bugfixes concerning the serial connections (https://github.com/pymodbus-dev/pymodbus/releases/tag/v3.4.0).

Can you add an explicit dependency "pyModbus==3.4.0" in your manifest.json, restart HA and check if that fixes things? I cannot test on my own inverter as I seem to have fried its serial port somewhere last year during development 🙈

[danise76]

Changed manifest.json to:

{
"domain": "huawei_solar",
"name": "Huawei Solar",
"config_flow": true,
"dependencies": [],
"documentation": "https://github.com/wlcrs/huawei_solar/wiki",
"issue_tracker": "https://github.com/wlcrs/huawei_solar/issues",
"requirements": ["huawei-solar==2.2.8", "pyModbus==3.4.0"],
"codeowners": ["@wlcrs"],
"iot_class": "local_polling",
"version": "1.2.6",
"loggers": ["huawei_solar", "pymodbus"]
}

Not working. The debug:

2023-07-23 08:59:29.465 DEBUG (MainThread) [pymodbus.logging] Connecting to /dev/serial/by-id/usb-1a86_USB2.0-Ser_-if00-port0.
2023-07-23 08:59:29.465 DEBUG (MainThread) [pymodbus.logging] Connecting comm
2023-07-23 08:59:29.469 DEBUG (MainThread) [pymodbus.logging] Connected to comm
2023-07-23 08:59:29.469 DEBUG (MainThread) [pymodbus.logging] callback_connected called
2023-07-23 08:59:29.470 DEBUG (MainThread) [huawei_solar.modbus] Waiting for 1500 milliseconds after connection before performing operations
2023-07-23 08:59:30.472 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 43006 with length 1 from slave 1
2023-07-23 08:59:30.472 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0xa7 0xfe 0x0 0x1 0xc6 0x8e
2023-07-23 08:59:30.472 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:30.505 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x3c 0xb8 0x55 addr=None
2023-07-23 08:59:30.506 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x3c 0xb8 0x55
2023-07-23 08:59:30.506 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x3c
2023-07-23 08:59:30.506 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:30.506 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:30.506 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:30.507 DEBUG (MainThread) [pymodbus.logging] [60]
2023-07-23 08:59:30.559 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000 with length 1 from slave 1
2023-07-23 08:59:30.559 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0xb7 0x98 0x0 0x1 0x22 0x51
2023-07-23 08:59:30.559 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44 addr=None
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:30.595 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:30.646 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089 with length 1 from slave 1
2023-07-23 08:59:30.647 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0xb7 0xf1 0x0 0x1 0xf2 0x4d
2023-07-23 08:59:30.648 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:30.823 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44 addr=None
2023-07-23 08:59:30.824 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44
2023-07-23 08:59:30.824 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:30.824 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:30.825 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:30.825 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:30.825 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:30.877 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30000 with length 35 from slave 1
2023-07-23 08:59:30.878 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x75 0x30 0x0 0x23 0x1e 0x10
2023-07-23 08:59:30.878 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:30.937 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x35 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 addr=None
2023-07-23 08:59:30.937 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x35 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:30.938 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:30.974 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x31 0x30 0x32 0x30 0x41 0x30 0x30 0x32 0x36 0x32 0x39 0x38 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x34 0x36 0x0 0x0 0x0 addr=None
2023-07-23 08:59:30.974 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x31 0x30 0x32 0x30 0x41 0x30 0x30 0x32 0x36 0x32 0x39 0x38 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x34 0x36 0x0 0x0 0x0
2023-07-23 08:59:30.974 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:30.989 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x9 0x1 addr=None
2023-07-23 08:59:30.989 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x9 0x1
2023-07-23 08:59:30.990 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x35 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x31 0x30 0x32 0x30 0x41 0x30 0x30 0x32 0x36 0x32 0x39 0x38 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x34 0x36 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:30.990 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:30.990 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:30.990 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:30.990 DEBUG (MainThread) [pymodbus.logging] [21333, 20018, 12336, 12333, 13643, 21580, 11596, 12544, 0, 0, 0, 0, 0, 0, 0, 12592, 12848, 16688, 12338, 13874, 14648, 0, 0, 0, 0, 12337, 12343, 13619, 13366, 0, 0, 0, 0, 0, 0]
2023-07-23 08:59:31.042 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30071 with length 1 from slave 1
2023-07-23 08:59:31.042 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x75 0x77 0x0 0x1 0x2e 0x1c
2023-07-23 08:59:31.043 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.076 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x2 0x39 0x85 addr=None
2023-07-23 08:59:31.077 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x2 0x39 0x85
2023-07-23 08:59:31.077 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x2
2023-07-23 08:59:31.077 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.077 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.077 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.078 DEBUG (MainThread) [pymodbus.logging] [2]
2023-07-23 08:59:31.130 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37200 with length 1 from slave 1
2023-07-23 08:59:31.130 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x91 0x50 0x0 0x1 0xa8 0xe7
2023-07-23 08:59:31.131 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.164 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x2 0x39 0x85 addr=None
2023-07-23 08:59:31.165 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x2 0x39 0x85
2023-07-23 08:59:31.165 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x2
2023-07-23 08:59:31.165 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.165 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.165 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.167 DEBUG (MainThread) [pymodbus.logging] [2]
2023-07-23 08:59:31.219 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000 with length 1 from slave 1
2023-07-23 08:59:31.219 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0xb7 0x98 0x0 0x1 0x22 0x51
2023-07-23 08:59:31.220 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.253 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44 addr=None
2023-07-23 08:59:31.254 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44
2023-07-23 08:59:31.254 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:31.254 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.254 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.254 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.255 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:31.306 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089 with length 1 from slave 1
2023-07-23 08:59:31.307 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0xb7 0xf1 0x0 0x1 0xf2 0x4d
2023-07-23 08:59:31.307 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.347 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44 addr=None
2023-07-23 08:59:31.348 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44
2023-07-23 08:59:31.348 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:31.348 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.348 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.348 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.349 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:31.400 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37100 with length 1 from slave 1
2023-07-23 08:59:31.401 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x90 0xec 0x0 0x1 0x68 0xff
2023-07-23 08:59:31.401 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.467 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x1 0x79 0x84 addr=None
2023-07-23 08:59:31.468 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x1 0x79 0x84
2023-07-23 08:59:31.468 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x1
2023-07-23 08:59:31.468 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.468 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.468 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.469 DEBUG (MainThread) [pymodbus.logging] [1]
2023-07-23 08:59:31.521 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37125 with length 1 from slave 1
2023-07-23 08:59:31.522 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x91 0x5 0x0 0x1 0xb8 0xf7
2023-07-23 08:59:31.522 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:31.555 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44 addr=None
2023-07-23 08:59:31.555 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x0 0xb8 0x44
2023-07-23 08:59:31.556 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:31.556 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.556 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.556 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:31.557 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:31.608 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000 with length 1 from slave 2
2023-07-23 08:59:31.608 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0xb7 0x98 0x0 0x1 0x22 0x62
2023-07-23 08:59:31.609 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:31.642 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44 addr=None
2023-07-23 08:59:31.643 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44
2023-07-23 08:59:31.643 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:31.643 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.643 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.644 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:31.645 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:31.697 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089 with length 1 from slave 2
2023-07-23 08:59:31.698 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0xb7 0xf1 0x0 0x1 0xf2 0x7e
2023-07-23 08:59:31.698 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:31.731 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44 addr=None
2023-07-23 08:59:31.731 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44
2023-07-23 08:59:31.732 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:31.732 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.732 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.732 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:31.733 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:31.786 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30000 with length 35 from slave 2
2023-07-23 08:59:31.786 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0x75 0x30 0x0 0x23 0x1e 0x23
2023-07-23 08:59:31.787 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:31.852 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x32 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 addr=None
2023-07-23 08:59:31.853 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x32 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:31.853 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:31.889 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x48 0x56 0x32 0x32 0x35 0x30 0x34 0x32 0x37 0x37 0x38 0x33 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x35 0x31 0x2d 0x30 0x31 addr=None
2023-07-23 08:59:31.889 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x48 0x56 0x32 0x32 0x35 0x30 0x34 0x32 0x37 0x37 0x38 0x33 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x35 0x31 0x2d 0x30 0x31
2023-07-23 08:59:31.889 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:31.904 DEBUG (MainThread) [pymodbus.logging] recv: 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x17 0xfd addr=None
2023-07-23 08:59:31.904 DEBUG (MainThread) [pymodbus.logging] Processing: 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x17 0xfd
2023-07-23 08:59:31.905 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x32 0x4b 0x54 0x4c 0x2d 0x4c 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x48 0x56 0x32 0x32 0x35 0x30 0x34 0x32 0x37 0x37 0x38 0x33 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x35 0x31 0x2d 0x30 0x31 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:31.905 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.905 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.905 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:31.906 DEBUG (MainThread) [pymodbus.logging] [21333, 20018, 12336, 12333, 12875, 21580, 11596, 12544, 0, 0, 0, 0, 0, 0, 0, 18518, 12850, 13616, 13362, 14135, 14387, 0, 0, 0, 0, 12337, 12343, 13619, 13617, 11568, 12597, 0, 0, 0, 0]
2023-07-23 08:59:31.957 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30071 with length 1 from slave 2
2023-07-23 08:59:31.958 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0x75 0x77 0x0 0x1 0x2e 0x2f
2023-07-23 08:59:31.958 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x2 0x7d 0x85 addr=None
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x2 0x7d 0x85
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x2
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:31.992 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:31.993 DEBUG (MainThread) [pymodbus.logging] [2]
2023-07-23 08:59:32.044 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37200 with length 1 from slave 2
2023-07-23 08:59:32.045 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0x91 0x50 0x0 0x1 0xa8 0xd4
2023-07-23 08:59:32.045 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x3 0xbc 0x45 addr=None
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x3 0xbc 0x45
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x3
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:32.078 DEBUG (MainThread) [pymodbus.logging] [3]
2023-07-23 08:59:32.129 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000 with length 1 from slave 2
2023-07-23 08:59:32.130 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0xb7 0x98 0x0 0x1 0x22 0x62
2023-07-23 08:59:32.130 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:32.163 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44 addr=None
2023-07-23 08:59:32.164 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44
2023-07-23 08:59:32.164 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:32.164 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.164 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.164 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:32.165 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:32.216 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089 with length 1 from slave 2
2023-07-23 08:59:32.217 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0xb7 0xf1 0x0 0x1 0xf2 0x7e
2023-07-23 08:59:32.217 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:32.262 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44 addr=None
2023-07-23 08:59:32.262 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44
2023-07-23 08:59:32.263 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:32.263 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.263 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.263 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:32.264 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:32.315 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37100 with length 1 from slave 2
2023-07-23 08:59:32.316 DEBUG (MainThread) [pymodbus.logging] send: 0x2 0x3 0x90 0xec 0x0 0x1 0x68 0xcc
2023-07-23 08:59:32.316 DEBUG (MainThread) [pymodbus.logging] Adding transaction 2
2023-07-23 08:59:32.349 DEBUG (MainThread) [pymodbus.logging] recv: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44 addr=None
2023-07-23 08:59:32.349 DEBUG (MainThread) [pymodbus.logging] Processing: 0x2 0x3 0x2 0x0 0x0 0xfc 0x44
2023-07-23 08:59:32.349 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x0
2023-07-23 08:59:32.350 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.350 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.350 DEBUG (MainThread) [pymodbus.logging] Getting transaction 2
2023-07-23 08:59:32.350 DEBUG (MainThread) [pymodbus.logging] [0]
2023-07-23 08:59:32.402 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32064 with length 52 from slave 1
2023-07-23 08:59:32.402 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x7d 0x40 0x0 0x34 0x5d 0xa5
2023-07-23 08:59:32.402 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:32.462 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x68 0x0 0x0 0x1 0xc5 0x9 0x9 0x0 0x0 0x0 0x0 0x8 0x14 0x0 0x3 0x0 0x0 0x0 0x0 0x7 0x86 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 addr=None
2023-07-23 08:59:32.463 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x68 0x0 0x0 0x1 0xc5 0x9 0x9 0x0 0x0 0x0 0x0 0x8 0x14 0x0 0x3 0x0 0x0 0x0 0x0 0x7 0x86 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:32.463 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.499 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x1 0xc7 0x0 0x0 0x1 0xb8 0x0 0x0 0x0 0x2 0x3 0xe8 0x13 0x88 0x25 0xf1 0x1 0x3f 0xb 0xb8 0x2 0x0 0x0 0x0 0x64 0xbc 0xd1 0x37 0x64 0xbc 0x4e addr=None
2023-07-23 08:59:32.499 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x1 0xc7 0x0 0x0 0x1 0xb8 0x0 0x0 0x0 0x2 0x3 0xe8 0x13 0x88 0x25 0xf1 0x1 0x3f 0xb 0xb8 0x2 0x0 0x0 0x0 0x64 0xbc 0xd1 0x37 0x64 0xbc 0x4e
2023-07-23 08:59:32.500 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.539 DEBUG (MainThread) [pymodbus.logging] recv: 0x6a 0x0 0x0 0x1 0xbe 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x14 0x90 0xa0 0x0 0x15 0x14 0xd 0x64 addr=None
2023-07-23 08:59:32.539 DEBUG (MainThread) [pymodbus.logging] Processing: 0x6a 0x0 0x0 0x1 0xbe 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x14 0x90 0xa0 0x0 0x15 0x14 0xd 0x64
2023-07-23 08:59:32.539 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.553 DEBUG (MainThread) [pymodbus.logging] recv: 0xbc 0xeb 0xef 0x0 0x0 0x0 0x18 0x0 0x0 0x0 0x1f 0x15 0x2 addr=None
2023-07-23 08:59:32.553 DEBUG (MainThread) [pymodbus.logging] Processing: 0xbc 0xeb 0xef 0x0 0x0 0x0 0x18 0x0 0x0 0x0 0x1f 0x15 0x2
2023-07-23 08:59:32.554 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x68 0x0 0x0 0x1 0xc5 0x9 0x9 0x0 0x0 0x0 0x0 0x8 0x14 0x0 0x3 0x0 0x0 0x0 0x0 0x7 0x86 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x1 0xc7 0x0 0x0 0x1 0xb8 0x0 0x0 0x0 0x2 0x3 0xe8 0x13 0x88 0x25 0xf1 0x1 0x3f 0xb 0xb8 0x2 0x0 0x0 0x0 0x64 0xbc 0xd1 0x37 0x64 0xbc 0x4e 0x6a 0x0 0x0 0x1 0xbe 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x14 0x90 0xa0 0x0 0x15 0x14 0xd 0x64 0xbc 0xeb 0xef 0x0 0x0 0x0 0x18 0x0 0x0 0x0 0x1f
2023-07-23 08:59:32.554 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.554 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.554 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] [0, 453, 2313, 0, 0, 2068, 3, 0, 0, 1926, 0, 0, 0, 0, 0, 455, 0, 440, 0, 2, 1000, 5000, 9713, 319, 3000, 512, 0, 25788, 53559, 25788, 20074, 0, 446, 0, 0, 0, 0, 0, 0, 0, 0, 0, 20, 37024, 21, 5133, 25788, 60399, 0, 24, 0, 31]
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x01\xc5']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x07\x86']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x00']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x00']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x01\xc7']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x01\xb8']
2023-07-23 08:59:32.555 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x02']
2023-07-23 08:59:32.556 DEBUG (MainThread) [pymodbus.logging] handle: [b'd\xbc', b'\xd17']
2023-07-23 08:59:32.556 DEBUG (MainThread) [pymodbus.logging] handle: [b'd\xbc', b'Nj']
2023-07-23 08:59:32.556 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x14', b'\x90\xa0']
2023-07-23 08:59:32.556 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x1f']
2023-07-23 08:59:32.607 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32000 with length 20 from slave 1
2023-07-23 08:59:32.608 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x7d 0x0 0x0 0x14 0x5d 0xa9
2023-07-23 08:59:32.608 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:32.668 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x28 0x0 0x6 0x0 0x0 0x0 0x7 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 addr=None
2023-07-23 08:59:32.668 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x28 0x0 0x6 0x0 0x0 0x0 0x7 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2023-07-23 08:59:32.669 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.686 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x95 0x40 0xa 0x15 0x0 0xb0 0x0 0x0 0x0 0x0 0x32 0xb8 addr=None
2023-07-23 08:59:32.686 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x95 0x40 0xa 0x15 0x0 0xb0 0x0 0x0 0x0 0x0 0x32 0xb8
2023-07-23 08:59:32.686 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x28 0x0 0x6 0x0 0x0 0x0 0x7 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x95 0x40 0xa 0x15 0x0 0xb0 0x0 0x0 0x0 0x0
2023-07-23 08:59:32.687 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.687 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.687 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:32.689 DEBUG (MainThread) [pymodbus.logging] [6, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 38208, 2581, 176, 0, 0]
2023-07-23 08:59:32.689 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x00']
2023-07-23 08:59:32.741 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37201 with length 1 from slave 1
2023-07-23 08:59:32.742 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x91 0x51 0x0 0x1 0xf9 0x27
2023-07-23 08:59:32.742 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:32.775 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x2 0x0 0x2 0x39 0x85 addr=None
2023-07-23 08:59:32.776 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x2 0x0 0x2 0x39 0x85
2023-07-23 08:59:32.776 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x2 0x0 0x2
2023-07-23 08:59:32.776 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.776 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.776 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:32.777 DEBUG (MainThread) [pymodbus.logging] [2]
2023-07-23 08:59:32.829 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37100 with length 38 from slave 1
2023-07-23 08:59:32.830 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x3 0x90 0xec 0x0 0x26 0x28 0xe5
2023-07-23 08:59:32.830 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:32.890 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x3 0x4c 0x0 0x1 0x0 0x0 0x9 0xb 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1 0x3b 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1 addr=None
2023-07-23 08:59:32.891 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x3 0x4c 0x0 0x1 0x0 0x0 0x9 0xb 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1 0x3b 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1
2023-07-23 08:59:32.891 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.927 DEBUG (MainThread) [pymodbus.logging] recv: 0x89 0x0 0x0 0x2 0x69 0x2 0x14 0x13 0x88 0x0 0x8 0xd0 0x3b 0x0 0x20 0xd2 0x7a 0x0 0x0 0x0 0x0 0x0 0x0 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f addr=None
2023-07-23 08:59:32.928 DEBUG (MainThread) [pymodbus.logging] Processing: 0x89 0x0 0x0 0x2 0x69 0x2 0x14 0x13 0x88 0x0 0x8 0xd0 0x3b 0x0 0x20 0xd2 0x7a 0x0 0x0 0x0 0x0 0x0 0x0 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f
2023-07-23 08:59:32.928 DEBUG (MainThread) [pymodbus.logging] Frame - not ready
2023-07-23 08:59:32.949 DEBUG (MainThread) [pymodbus.logging] recv: 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x80 0x89 addr=None
2023-07-23 08:59:32.950 DEBUG (MainThread) [pymodbus.logging] Processing: 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x80 0x89
2023-07-23 08:59:32.950 DEBUG (MainThread) [pymodbus.logging] Getting Frame - 0x3 0x4c 0x0 0x1 0x0 0x0 0x9 0xb 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1 0x3b 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x0 0x0 0x1 0x89 0x0 0x0 0x2 0x69 0x2 0x14 0x13 0x88 0x0 0x8 0xd0 0x3b 0x0 0x20 0xd2 0x7a 0x0 0x0 0x0 0x0 0x0 0x0 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff 0x7f 0xff 0xff 0xff
2023-07-23 08:59:32.950 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2023-07-23 08:59:32.950 DEBUG (MainThread) [pymodbus.logging] Frame advanced, resetting header!!
2023-07-23 08:59:32.951 DEBUG (MainThread) [pymodbus.logging] Getting transaction 1
2023-07-23 08:59:32.951 DEBUG (MainThread) [pymodbus.logging] [1, 0, 2315, 32767, 65535, 32767, 65535, 0, 315, 32767, 65535, 32767, 65535, 0, 393, 0, 617, 532, 5000, 8, 53307, 32, 53882, 0, 0, 0, 32767, 65535, 32767, 65535, 32767, 65535, 32767, 65535, 32767, 65535, 32767, 65535]
2023-07-23 08:59:32.951 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\t\x0b']
2023-07-23 08:59:32.951 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x01;']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x01\x89']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x02i']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x08', b'\xd0;']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00 ', b'\xd2z']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x00\x00', b'\x00\x00']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.952 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.953 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.953 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.953 DEBUG (MainThread) [pymodbus.logging] handle: [b'\x7f\xff', b'\xff\xff']
2023-07-23 08:59:32.953 DEBUG (MainThread) [custom_components.huawei_solar] Finished fetching 1020A0026298_data_update_coordinator data in 0.602 seconds (success: True)
2023-07-23 08:59:33.004 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading file 0x45 from slave 1
2023-07-23 08:59:33.005 DEBUG (MainThread) [pymodbus.logging] send: 0x1 0x41 0x5 0x1 0x45 0xdd 0x9e
2023-07-23 08:59:33.005 DEBUG (MainThread) [pymodbus.logging] Adding transaction 1
2023-07-23 08:59:33.076 DEBUG (MainThread) [pymodbus.logging] recv: 0x1 0x41 0x5 0xa 0x45 0x0 0x0 0x0 0xe6 0xf6 0xff 0xff 0xff 0xff 0xb7 0x16 addr=None
2023-07-23 08:59:33.076 DEBUG (MainThread) [pymodbus.logging] Processing: 0x1 0x41 0x5 0xa 0x45 0x0 0x0 0x0 0xe6 0xf6 0xff 0xff 0xff 0xff 0xb7 0x16
2023-07-23 08:59:33.076 DEBUG (MainThread) [pymodbus.logging] Frame check failed, ignoring!!
2023-07-23 08:59:33.076 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 0x41 0x5 0xa 0x45 0x0 0x0 0x0 0xe6 0xf6 0xff 0xff 0xff 0xff 0xb7 0x16
2023-07-23 08:59:43.008 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry SUN2000-5KTL-L1 for huawei_solar
Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/config_entries.py", line 390, in async_setup
result = await component.async_setup_entry(hass, self)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/config/custom_components/huawei_solar/init.py", line 148, in async_setup_entry
await bridge.get_optimizer_system_information_data()
File "/usr/local/lib/python3.11/site-packages/huawei_solar/bridge.py", line 277, in get_optimizer_system_information_data
file_data = await self._read_file(OptimizerSystemInformationDataFile.FILE_TYPE)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/huawei_solar/bridge.py", line 235, in _read_file
return await self.client.get_file(file_type, customized_data, self.slave_id)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 483, in get_file
return await _do_read_file()
^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 441, in _do_read_file
start_upload_response = await _perform_request(
^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/backoff/_async.py", line 151, in retry
ret = await target(*args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/huawei_solar/huawei_solar.py", line 423, in _perform_request
response = await self._client.execute(request)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/site-packages/pymodbus/client/base.py", line 201, in async_execute
resp = await asyncio.wait_for(
^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/local/lib/python3.11/asyncio/tasks.py", line 479, in wait_for
return fut.result()
^^^^^^^^^^^^
asyncio.exceptions.CancelledError

[wlcrs]

@danise76 , someone else submitted a similar bugreport for serial connections in #426 . Can you please follow the instructions in #426 (comment) to help find the regression in pyModbus?

[danise76]

Same here as in the #426. It works setting the version 3.2.2 (works up to that one) in manifest.json:

"requirements": ["huawei-solar==2.2.8", "pyModbus==3.2.2"],

I left it updated to 1.2.6 but manually setting the manifest.json to that pyModbus version.

[wlcrs]

Can you please test with release 1.2.7 beta 2 of this integration? Please open a new bug report with new logs if necessary.