[Bug]: After latest upgrade integration doesn't work

johanvh83 commented 1 year ago

Describe the issue

Upgraded huawei_solar integration to latest version by HACS Restarted HA but all entities are 'unavailable'. Checked HA log but no relevant error about the integration. Re-loaded the integration but no progress. Enabled debug logging and restarted HA. Problem not resolved as suspected but where to find the debug log?

Anything else to check?

Bescribe your Huawei Solar Setup

Inverter Type: SUN2000-4.6KTL-L1 Inverter Firmware version: Unknown SDongle present: no Power meter present: single phase Battery: LUNA2000 10 kWh Battery Firmware version: Unknown

How do you connect to the inverter?

By Wifi

Upload your Diagnostics File

Drag & Drop your Diagnostics File here. config_entry-huawei_solar-b67bf3ea1f0b71e75077c75ebcbdc0cc.json.txt

Upload your relevant debug logs

None

Please confirm the following:

[X] The problem is still present in the latest release of this integration.
[X] I did not find an existing issue describing this problem.
[X] I did upload the diagnostics-file that I could retrieve from the 'Devices & Services Page'
[X] I increased the logging configuration, restarted HA, and have copied all relevant log lines from the 'Full Logs' into the textarea.

peros550 commented 1 year ago

Same here . Downgraded and worked fine again

johanvh83 commented 1 year ago

Thanks to confirm so I'm not the only one. Downgrade is in progress. Hopefully wlcrs can resolve the problem

wlcrs commented 1 year ago

I can only resolve something if I have an error message that tells me what is going wrong.

Go to Settings -> System -> Logs, and use the 'Load full logs' button on the bottom of the page. You should see the debug information from the integration appear on there.

johanvh83 commented 1 year ago

Hi,

The only thing the full log shows is many items like: 2022-12-23 14:23:25.517 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37760 with different value at the end... No error message at all. I've uploaded the diagnosis file. It seems this diagnosis file contains all data from the inverter but all entities are 'unavailable'

johanvh83 commented 1 year ago

I've put back my latest backup from this night and all works well again. Problem started after upgrade from 1.2.0b1 to 1.2.0b2

dMopp commented 1 year ago

Same here, stops working with current beta

wlcrs commented 1 year ago

instead of 'me too' comments, I would appreciate anyone giving more input on the actual issue, ie. an upload of the full logs with debugging enabled. (cfr. manual on how to enable them)

For the moment I have not enough info to work with.

peros550 commented 1 year ago

@wlcrs apologies for the "me too" message. I am newbie with HA, and didn't know how to get logs, so your link above helped me a lot. Here are logs from my case. The HA is connected to Inverter's wifi dongle which is connected to my router.

So here is my log

2022-12-23 23:06:44.477 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-12-23 23:06:44.480 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration huawei_solar which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-12-23 23:06:44.482 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration climate_ip which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-12-23 23:06:58.023 DEBUG (MainThread) [pymodbus.client.tcp] Connecting to 192.168.177.76:502.
2022-12-23 23:06:58.023 DEBUG (MainThread) [pymodbus.client.tcp] Connecting.
2022-12-23 23:06:59.188 DEBUG (MainThread) [pymodbus.client.base] Client connected to modbus server
2022-12-23 23:06:59.188 INFO (MainThread) [pymodbus.client.tcp] Protocol made connection.
2022-12-23 23:06:59.320 INFO (MainThread) [pymodbus.client.tcp] Connected to 192.168.177.76:502.
2022-12-23 23:07:00.483 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 43006
2022-12-23 23:07:00.485 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x1 0x0 0x0 0x0 0x6 0x1 0x3 0xa7 0xfe 0x0 0x1
2022-12-23 23:07:00.487 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 1
2022-12-23 23:07:02.786 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x1 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x78
2022-12-23 23:07:02.786 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x1 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x78
2022-12-23 23:07:02.787 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:02.787 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 1
2022-12-23 23:07:02.858 WARNING (MainThread) [homeassistant.config_entries] Config entry '192.168.177.71' for daikin integration not ready yet: Server disconnected; Retrying in background
2022-12-23 23:07:03.140 DEBUG (MainThread) [pymodbus.payload] [120]
2022-12-23 23:07:03.143 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000
2022-12-23 23:07:03.144 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x2 0x0 0x0 0x0 0x6 0x1 0x3 0xb7 0x98 0x0 0x1
2022-12-23 23:07:03.154 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 2
2022-12-23 23:07:03.374 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x2 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:03.375 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x2 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:03.375 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:03.375 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 2
2022-12-23 23:07:03.623 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-23 23:07:03.623 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30000
2022-12-23 23:07:03.624 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x3 0x0 0x0 0x0 0x6 0x1 0x3 0x75 0x30 0x0 0x23
2022-12-23 23:07:03.624 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 3
2022-12-23 23:07:03.917 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x3 0x0 0x0 0x0 0x49 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 0x0 0x48 0x56 0x32 0x32 0x36 0x33 0x34 0x32 0x31 0x39 0x30 0x36 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x34 0x36 0x2d 0x30 0x31 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-23 23:07:03.918 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x3 0x0 0x0 0x0 0x49 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 0x0 0x48 0x56 0x32 0x32 0x36 0x33 0x34 0x32 0x31 0x39 0x30 0x36 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x35 0x33 0x34 0x36 0x2d 0x30 0x31 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-23 23:07:03.918 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:03.918 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 3
2022-12-23 23:07:03.971 DEBUG (MainThread) [pymodbus.payload] [21333, 20018, 12336, 12333, 13643, 21580, 11596, 12544, 0, 0, 0, 0, 0, 0, 0, 18518, 12850, 13875, 13362, 12601, 12342, 0, 0, 0, 0, 12337, 12343, 13619, 13366, 11568, 12597, 0, 0, 0, 0]
2022-12-23 23:07:03.971 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30071
2022-12-23 23:07:03.972 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x4 0x0 0x0 0x0 0x6 0x1 0x3 0x75 0x77 0x0 0x1
2022-12-23 23:07:03.974 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 4
2022-12-23 23:07:04.600 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x4 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x2
2022-12-23 23:07:04.600 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x4 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x2
2022-12-23 23:07:04.601 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:04.601 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 4
2022-12-23 23:07:04.652 DEBUG (MainThread) [pymodbus.payload] [2]
2022-12-23 23:07:04.653 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37200
2022-12-23 23:07:04.654 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x5 0x0 0x0 0x0 0x6 0x1 0x3 0x91 0x50 0x0 0x1
2022-12-23 23:07:04.655 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 5
2022-12-23 23:07:04.905 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:04.906 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:04.906 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:04.906 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 5
2022-12-23 23:07:04.957 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-23 23:07:04.958 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37100
2022-12-23 23:07:04.958 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x6 0x0 0x0 0x0 0x6 0x1 0x3 0x90 0xec 0x0 0x1
2022-12-23 23:07:04.960 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 6
2022-12-23 23:07:05.008 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x6 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.009 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x6 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.009 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:05.009 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 6
2022-12-23 23:07:05.065 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-23 23:07:05.066 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000
2022-12-23 23:07:05.067 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x7 0x0 0x0 0x0 0x6 0x1 0x3 0xb7 0x98 0x0 0x1
2022-12-23 23:07:05.070 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 7
2022-12-23 23:07:05.525 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x7 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.525 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x7 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.526 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:05.526 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 7
2022-12-23 23:07:05.579 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-23 23:07:05.579 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089
2022-12-23 23:07:05.580 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x8 0x0 0x0 0x0 0x6 0x1 0x3 0xb7 0xf1 0x0 0x1
2022-12-23 23:07:05.584 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 8
2022-12-23 23:07:05.829 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x8 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.831 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x8 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x0
2022-12-23 23:07:05.831 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:05.832 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 8
2022-12-23 23:07:05.891 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-23 23:07:05.893 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32064
2022-12-23 23:07:05.894 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x9 0x0 0x0 0x0 0x6 0x1 0x3 0x7d 0x40 0x0 0x34
2022-12-23 23:07:05.903 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 9
2022-12-23 23:07:06.090 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x9 0x0 0x0 0x0 0x6b 0x1 0x3 0x68 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x9 0x78 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0xb 0xb8 0xa0 0x0 0x0 0x0 0x63 0xa5 0x5a 0xaa 0x63 0xa5 0xe2 0x76 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x5a 0x7a 0x0 0x0 0x5d 0x20 0x63 0xa6 0x34 0x92 0x0 0x0 0x0 0x0 0x0 0x0 0x4 0xf3
2022-12-23 23:07:06.090 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x9 0x0 0x0 0x0 0x6b 0x1 0x3 0x68 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x9 0x78 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0xb 0xb8 0xa0 0x0 0x0 0x0 0x63 0xa5 0x5a 0xaa 0x63 0xa5 0xe2 0x76 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x5a 0x7a 0x0 0x0 0x5d 0x20 0x63 0xa6 0x34 0x92 0x0 0x0 0x0 0x0 0x0 0x0 0x4 0xf3
2022-12-23 23:07:06.090 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:06.090 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 9
2022-12-23 23:07:06.142 DEBUG (MainThread) [pymodbus.payload] [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 2424, 0, 0, 0, 0, 0, 0, 0, 0, 3000, 40960, 0, 25509, 23210, 25509, 57974, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 23162, 0, 23840, 25510, 13458, 0, 0, 0, 1267]
2022-12-23 23:07:06.142 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\tx']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.143 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:07:06.144 DEBUG (MainThread) [pymodbus.payload] [b'c\xa5', b'Z\xaa']
2022-12-23 23:07:06.144 DEBUG (MainThread) [pymodbus.payload] [b'c\xa5', b'\xe2v']
2022-12-23 23:07:06.144 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'Zz']
2022-12-23 23:07:06.144 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x04\xf3']
2022-12-23 23:07:06.144 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32000
2022-12-23 23:07:06.145 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0xa 0x0 0x0 0x0 0x6 0x1 0x3 0x7d 0x0 0x0 0x14
2022-12-23 23:07:06.148 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 10
2022-12-23 23:07:06.430 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0xa 0x0 0x0 0x0 0x2b 0x1 0x3 0x28 0x0 0x1 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x65 0xd 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-23 23:07:06.430 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0xa 0x0 0x0 0x0 0x2b 0x1 0x3 0x28 0x0 0x1 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x65 0xd 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-23 23:07:06.430 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-23 23:07:06.431 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 10
2022-12-23 23:07:06.483 DEBUG (MainThread) [pymodbus.payload] [1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 25869, 0, 0, 0, 0]
2022-12-23 23:07:06.483 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-23 23:10:05.733 DEBUG (MainThread) [pymodbus.client.base] Client disconnected from modbus server: None
2022-12-23 23:10:05.733 INFO (MainThread) [pymodbus.client.tcp] Protocol lost connection.
2022-12-23 23:10:05.742 DEBUG (MainThread) [pymodbus.client.tcp] Waiting 1000 ms before next connection attempt.
2022-12-23 23:10:06.744 DEBUG (MainThread) [pymodbus.client.tcp] Connecting.
2022-12-23 23:10:06.756 DEBUG (MainThread) [pymodbus.client.base] Client connected to modbus server
2022-12-23 23:10:06.756 INFO (MainThread) [pymodbus.client.tcp] Protocol made connection.
2022-12-23 23:10:06.759 INFO (MainThread) [pymodbus.client.tcp] Connected to 192.168.177.76:502.

clunit commented 1 year ago

For context, I was running 1.2.0b4 without any issues since Monday, but I have not used any previous versions as I just got my SUN/LUNA installed last weekend.

yousaf465 commented 1 year ago

Me too logs added as below

2022-12-24 12:32:21.365 DEBUG (MainThread) [pymodbus.payload] [300]
2022-12-24 12:32:21.367 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000
2022-12-24 12:32:21.368 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x2 0x0 0x0 0x0 0x6 0x0 0x3 0xb7 0x98 0x0 0x1
2022-12-24 12:32:21.369 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 2
2022-12-24 12:32:21.420 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 26.0 seconds
2022-12-24 12:32:21.649 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x2 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:21.649 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x2 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:21.649 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:21.649 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 2
2022-12-24 12:32:21.652 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.7 seconds
2022-12-24 12:32:21.677 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.esphome
2022-12-24 12:32:21.678 INFO (MainThread) [homeassistant.components.switch] Setting up switch.esphome
2022-12-24 12:32:21.679 INFO (MainThread) [homeassistant.components.light] Setting up light.esphome
2022-12-24 12:32:21.680 INFO (MainThread) [homeassistant.components.button] Setting up button.esphome
2022-12-24 12:32:21.684 INFO (MainThread) [homeassistant.components.weather] Setting up weather.met
2022-12-24 12:32:21.712 INFO (MainThread) [homeassistant.components.islamic_prayer_times] Next update scheduled for: 2022-12-25 00:03:00
2022-12-24 12:32:21.746 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.islamic_prayer_times
2022-12-24 12:32:21.788 ERROR (MainThread) [homeassistant.components.sensor] Platform esphome does not generate unique IDs. ID 30c6f72f836c-uptime already exists - ignoring sensor.10_gal_aquarium_uptime
2022-12-24 12:32:21.852 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-24 12:32:21.853 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30000
2022-12-24 12:32:21.855 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x3 0x0 0x0 0x0 0x6 0x0 0x3 0x75 0x30 0x0 0x23
2022-12-24 12:32:21.856 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 3
2022-12-24 12:32:21.900 WARNING (MainThread) [aioesphomeapi.reconnect_logic] Can't connect to ESPHome API for camera @ 192.168.18.144: Error connecting to ('192.168.18.144', 6053): [Errno 113] Connect call failed ('192.168.18.144', 6053)
2022-12-24 12:32:21.911 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x3 0x0 0x0 0x0 0x49 0x0 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x31 0x30 0x4b 0x54 0x4c 0x2d 0x4d 0x31 0x0 0x0 0x0 0x0 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x31 0x32 0x48 0x56 0x32 0x31 0x35 0x30 0x30 0x36 0x37 0x35 0x39 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x34 0x33 0x31 0x31 0x2d 0x30 0x30 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-24 12:32:21.912 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x3 0x0 0x0 0x0 0x49 0x0 0x3 0x46 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x31 0x30 0x4b 0x54 0x4c 0x2d 0x4d 0x31 0x0 0x0 0x0 0x0 0x53 0x55 0x4e 0x32 0x30 0x30 0x30 0x2d 0x31 0x32 0x48 0x56 0x32 0x31 0x35 0x30 0x30 0x36 0x37 0x35 0x39 0x35 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x30 0x31 0x30 0x37 0x34 0x33 0x31 0x31 0x2d 0x30 0x30 0x31 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2022-12-24 12:32:21.912 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:21.912 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 3
2022-12-24 12:32:21.923 INFO (MainThread) [aioesphomeapi.reconnect_logic] Trying to reconnect to camera @ 192.168.18.144 in the background
2022-12-24 12:32:22.180 INFO (MainThread) [homeassistant.setup] Setting up cast
2022-12-24 12:32:22.181 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds
2022-12-24 12:32:22.217 DEBUG (MainThread) [pymodbus.payload] [21333, 20018, 12336, 12333, 12592, 19284, 19501, 19761, 0, 0, 21333, 20018, 12336, 12333, 12594, 18518, 12849, 13616, 12342, 14133, 14645, 0, 0, 0, 0, 12337, 12343, 13363, 12593, 11568, 12337, 0, 0, 0, 0]
2022-12-24 12:32:22.220 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 30071
2022-12-24 12:32:22.221 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x4 0x0 0x0 0x0 0x6 0x0 0x3 0x75 0x77 0x0 0x1
2022-12-24 12:32:22.222 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 4
2022-12-24 12:32:22.303 INFO (MainThread) [homeassistant.setup] Setting up media_player
2022-12-24 12:32:22.331 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.0 seconds
2022-12-24 12:32:22.339 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x4 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x2
2022-12-24 12:32:22.340 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x4 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x2
2022-12-24 12:32:22.340 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:22.340 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 4
2022-12-24 12:32:23.877 INFO (MainThread) [homeassistant.setup] Setting up default_config
2022-12-24 12:32:23.884 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast
2022-12-24 12:32:23.900 INFO (SyncWorker_3) [homeassistant.loader] Loaded stream from homeassistant.components.stream
2022-12-24 12:32:23.962 INFO (MainThread) [aioesphomeapi.reconnect_logic] Successfully connected to bmp280 @ 192.168.18.111
2022-12-24 12:32:23.964 INFO (MainThread) [aioesphomeapi.reconnect_logic] Successfully connected to esphome-aquarium-light @ 192.168.18.243
2022-12-24 12:32:23.982 DEBUG (MainThread) [pymodbus.payload] [2]
2022-12-24 12:32:23.983 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37200
2022-12-24 12:32:23.983 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x5 0x0 0x0 0x0 0x6 0x0 0x3 0x91 0x50 0x0 0x1
2022-12-24 12:32:23.985 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 5
2022-12-24 12:32:24.005 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x5 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.006 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x5 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.006 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.006 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 5
2022-12-24 12:32:24.011 INFO (MainThread) [homeassistant.setup] Setting up stream
2022-12-24 12:32:24.035 INFO (MainThread) [homeassistant.setup] Setup of domain stream took 0.0 seconds
2022-12-24 12:32:24.049 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.2 seconds
2022-12-24 12:32:24.096 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.upnp
2022-12-24 12:32:24.108 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.upnp
2022-12-24 12:32:24.139 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-24 12:32:24.140 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37100
2022-12-24 12:32:24.140 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x6 0x0 0x0 0x0 0x6 0x0 0x3 0x90 0xec 0x0 0x1
2022-12-24 12:32:24.144 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 6
2022-12-24 12:32:24.177 INFO (MainThread) [aioesphomeapi.reconnect_logic] Successfully connected to aquariumnode @ 192.168.18.223
2022-12-24 12:32:24.179 INFO (SyncWorker_7) [homeassistant.loader] Loaded backup from homeassistant.components.backup
2022-12-24 12:32:24.184 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x6 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x1
2022-12-24 12:32:24.185 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x6 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x1
2022-12-24 12:32:24.185 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.185 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 6
2022-12-24 12:32:24.260 INFO (MainThread) [homeassistant.setup] Setting up auto_backup
2022-12-24 12:32:24.261 INFO (MainThread) [homeassistant.setup] Setup of domain auto_backup took 0.0 seconds
2022-12-24 12:32:24.275 INFO (MainThread) [custom_components.auto_backup] Setting up Auto Backup config entry 4d271284c3bd9e57bb661d60bb807a6a
2022-12-24 12:32:24.277 DEBUG (MainThread) [pymodbus.payload] [1]
2022-12-24 12:32:24.277 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37125
2022-12-24 12:32:24.278 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x7 0x0 0x0 0x0 0x6 0x0 0x3 0x91 0x5 0x0 0x1
2022-12-24 12:32:24.279 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 7
2022-12-24 12:32:24.294 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.auto_backup
2022-12-24 12:32:24.296 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x7 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x1
2022-12-24 12:32:24.296 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x7 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x1
2022-12-24 12:32:24.297 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.297 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 7
2022-12-24 12:32:24.304 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.sonoff
2022-12-24 12:32:24.306 INFO (MainThread) [homeassistant.components.button] Setting up button.sonoff
2022-12-24 12:32:24.307 INFO (MainThread) [homeassistant.components.climate] Setting up climate.sonoff
2022-12-24 12:32:24.308 INFO (MainThread) [homeassistant.components.cover] Setting up cover.sonoff
2022-12-24 12:32:24.331 INFO (MainThread) [homeassistant.components.fan] Setting up fan.sonoff
2022-12-24 12:32:24.333 INFO (MainThread) [homeassistant.components.light] Setting up light.sonoff
2022-12-24 12:32:24.334 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.sonoff
2022-12-24 12:32:24.335 INFO (MainThread) [homeassistant.components.switch] Setting up switch.sonoff
2022-12-24 12:32:24.337 INFO (MainThread) [homeassistant.components.number] Setting up number.sonoff
2022-12-24 12:32:24.355 DEBUG (MainThread) [pymodbus.payload] [1]
2022-12-24 12:32:24.356 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47000
2022-12-24 12:32:24.356 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x8 0x0 0x0 0x0 0x6 0x0 0x3 0xb7 0x98 0x0 0x1
2022-12-24 12:32:24.357 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 8
2022-12-24 12:32:24.359 INFO (SyncWorker_8) [homeassistant.loader] Loaded remote from homeassistant.components.remote
2022-12-24 12:32:24.364 INFO (MainThread) [homeassistant.setup] Setting up remote
2022-12-24 12:32:24.373 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds
2022-12-24 12:32:24.376 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x8 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.377 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x8 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.377 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.377 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 8
2022-12-24 12:32:24.380 INFO (MainThread) [homeassistant.components.remote] Setting up remote.sonoff
2022-12-24 12:32:24.443 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-24 12:32:24.444 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 47089
2022-12-24 12:32:24.444 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0x9 0x0 0x0 0x0 0x6 0x0 0x3 0xb7 0xf1 0x0 0x1
2022-12-24 12:32:24.445 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 9
2022-12-24 12:32:24.493 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0x9 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.494 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0x9 0x0 0x0 0x0 0x5 0x0 0x3 0x2 0x0 0x0
2022-12-24 12:32:24.494 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.494 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 9
2022-12-24 12:32:24.549 DEBUG (MainThread) [pymodbus.payload] [0]
2022-12-24 12:32:24.550 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32064
2022-12-24 12:32:24.551 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0xa 0x0 0x0 0x0 0x6 0x0 0x3 0x7d 0x40 0x0 0x34
2022-12-24 12:32:24.555 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 10
2022-12-24 12:32:24.578 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0xa 0x0 0x0 0x0 0x6b 0x0 0x3 0x68 0x0 0x0 0xc 0x4f 0xf 0x41 0xf 0x24 0xf 0x48 0x8 0xd8 0x8 0xbc 0x8 0xcb 0x0 0x0 0x11 0xb7 0x0 0x0 0x11 0xe4 0x0 0x0 0x11 0xd2 0x0 0x0 0xc 0x18 0x0 0x0 0xb 0xfd 0x0 0x0 0x0 0x1 0x3 0xe8 0x13 0x95 0x26 0xc 0x0 0xfb 0xb 0xb8 0x2 0x0 0x0 0x0 0x63 0xa6 0xac 0x39 0xff 0xff 0xff 0xff 0x0 0x0 0xb 0xfd 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x12 0xb4 0xf 0x0 0x13 0x93 0xe3 0x63 0xa6 0xf1 0x50 0x0 0x0 0x0 0x3e 0x0 0x0 0x1 0x23
2022-12-24 12:32:24.578 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0xa 0x0 0x0 0x0 0x6b 0x0 0x3 0x68 0x0 0x0 0xc 0x4f 0xf 0x41 0xf 0x24 0xf 0x48 0x8 0xd8 0x8 0xbc 0x8 0xcb 0x0 0x0 0x11 0xb7 0x0 0x0 0x11 0xe4 0x0 0x0 0x11 0xd2 0x0 0x0 0xc 0x18 0x0 0x0 0xb 0xfd 0x0 0x0 0x0 0x1 0x3 0xe8 0x13 0x95 0x26 0xc 0x0 0xfb 0xb 0xb8 0x2 0x0 0x0 0x0 0x63 0xa6 0xac 0x39 0xff 0xff 0xff 0xff 0x0 0x0 0xb 0xfd 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x12 0xb4 0xf 0x0 0x13 0x93 0xe3 0x63 0xa6 0xf1 0x50 0x0 0x0 0x0 0x3e 0x0 0x0 0x1 0x23
2022-12-24 12:32:24.578 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.579 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 10
2022-12-24 12:32:24.631 DEBUG (MainThread) [pymodbus.payload] [0, 3151, 3905, 3876, 3912, 2264, 2236, 2251, 0, 4535, 0, 4580, 0, 4562, 0, 3096, 0, 3069, 0, 1, 1000, 5013, 9740, 251, 3000, 512, 0, 25510, 44089, 65535, 65535, 0, 3069, 0, 0, 0, 0, 0, 0, 0, 0, 0, 18, 46095, 19, 37859, 25510, 61776, 0, 62, 0, 291]
2022-12-24 12:32:24.632 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0cO']
2022-12-24 12:32:24.632 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x11\xb7']
2022-12-24 12:32:24.633 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x11\xe4']
2022-12-24 12:32:24.633 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x11\xd2']
2022-12-24 12:32:24.633 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0c\x18']
2022-12-24 12:32:24.633 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0b\xfd']
2022-12-24 12:32:24.633 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x01']
2022-12-24 12:32:24.634 DEBUG (MainThread) [pymodbus.payload] [b'c\xa6', b'\xac9']
2022-12-24 12:32:24.634 DEBUG (MainThread) [pymodbus.payload] [b'\xff\xff', b'\xff\xff']
2022-12-24 12:32:24.634 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x12', b'\xb4\x0f']
2022-12-24 12:32:24.635 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x01#']
2022-12-24 12:32:24.635 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 32000
2022-12-24 12:32:24.636 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0xb 0x0 0x0 0x0 0x6 0x0 0x3 0x7d 0x0 0x0 0x14
2022-12-24 12:32:24.637 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 11
2022-12-24 12:32:24.648 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0xb 0x0 0x0 0x0 0x2b 0x0 0x3 0x28 0x0 0x6 0x0 0x0 0x0 0x6 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0xa2 0x6 0xe 0xa0 0x1 0xa0 0xe 0x9e 0x1 0xb1
2022-12-24 12:32:24.649 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0xb 0x0 0x0 0x0 0x2b 0x0 0x3 0x28 0x0 0x6 0x0 0x0 0x0 0x6 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0xa2 0x6 0xe 0xa0 0x1 0xa0 0xe 0x9e 0x1 0xb1
2022-12-24 12:32:24.650 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.650 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 11
2022-12-24 12:32:24.704 DEBUG (MainThread) [pymodbus.payload] [6, 0, 6, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 41478, 3744, 416, 3742, 433]
2022-12-24 12:32:24.705 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-24 12:32:24.705 DEBUG (MainThread) [huawei_solar.huawei_solar] Reading register 37101
2022-12-24 12:32:24.706 DEBUG (MainThread) [pymodbus.client.base] send: 0x0 0xc 0x0 0x0 0x0 0x6 0x0 0x3 0x90 0xed 0x0 0x25
2022-12-24 12:32:24.707 DEBUG (MainThread) [pymodbus.transaction] Adding transaction 12
2022-12-24 12:32:24.770 DEBUG (MainThread) [pymodbus.client.base] recv: 0x0 0xc 0x0 0x0 0x0 0x4d 0x0 0x3 0x4a 0x0 0x0 0x8 0xbe 0x0 0x0 0x8 0xc8 0x0 0x0 0x8 0xd7 0x0 0x0 0x1 0xbe 0x0 0x0 0x1 0x60 0x0 0x0 0x0 0xf8 0x0 0x0 0x7 0x86 0xff 0xff 0xfe 0x68 0x3 0x3c 0x13 0x93 0x0 0x6 0x29 0xaf 0x0 0xc 0x4a 0xbe 0x0 0x0 0x0 0x0 0x0 0x1 0x0 0x0 0xf 0x2d 0x0 0x0 0xf 0x43 0x0 0x0 0xf 0x39 0x0 0x0 0x3 0xe4 0x0 0x0 0x2 0xaa 0x0 0x0 0x0 0xf8
2022-12-24 12:32:24.771 DEBUG (MainThread) [pymodbus.framer.socket_framer] Processing: 0x0 0xc 0x0 0x0 0x0 0x4d 0x0 0x3 0x4a 0x0 0x0 0x8 0xbe 0x0 0x0 0x8 0xc8 0x0 0x0 0x8 0xd7 0x0 0x0 0x1 0xbe 0x0 0x0 0x1 0x60 0x0 0x0 0x0 0xf8 0x0 0x0 0x7 0x86 0xff 0xff 0xfe 0x68 0x3 0x3c 0x13 0x93 0x0 0x6 0x29 0xaf 0x0 0xc 0x4a 0xbe 0x0 0x0 0x0 0x0 0x0 0x1 0x0 0x0 0xf 0x2d 0x0 0x0 0xf 0x43 0x0 0x0 0xf 0x39 0x0 0x0 0x3 0xe4 0x0 0x0 0x2 0xaa 0x0 0x0 0x0 0xf8
2022-12-24 12:32:24.771 DEBUG (MainThread) [pymodbus.factory] Factory Response[ReadHoldingRegistersResponse': 3]
2022-12-24 12:32:24.771 DEBUG (MainThread) [pymodbus.transaction] Getting transaction 12
2022-12-24 12:32:24.825 DEBUG (MainThread) [pymodbus.payload] [0, 2238, 0, 2248, 0, 2263, 0, 446, 0, 352, 0, 248, 0, 1926, 65535, 65128, 828, 5011, 6, 10671, 12, 19134, 0, 0, 1, 0, 3885, 0, 3907, 0, 3897, 0, 996, 0, 682, 0, 248]
2022-12-24 12:32:24.825 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x08\xbe']
2022-12-24 12:32:24.825 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x08\xc8']
2022-12-24 12:32:24.825 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x08\xd7']
2022-12-24 12:32:24.826 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x01\xbe']
2022-12-24 12:32:24.826 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x01`']
2022-12-24 12:32:24.826 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\xf8']
2022-12-24 12:32:24.827 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x07\x86']
2022-12-24 12:32:24.827 DEBUG (MainThread) [pymodbus.payload] [b'\xff\xff', b'\xfeh']
2022-12-24 12:32:24.827 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x06', b')\xaf']
2022-12-24 12:32:24.827 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x0c', b'J\xbe']
2022-12-24 12:32:24.827 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\x00']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0f-']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0fC']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x0f9']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x03\xe4']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x02\xaa']
2022-12-24 12:32:24.828 DEBUG (MainThread) [pymodbus.payload] [b'\x00\x00', b'\x00\xf8']
2022-12-24 12:32:24.842 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.huawei_solar
2022-12-24 12:32:24.856 INFO (MainThread) [homeassistant.components.number] Setting up number.huawei_solar
2022-12-24 12:32:24.867 INFO (MainThread) [homeassistant.components.switch] Setting up switch.huawei_solar
2022-12-24 12:32:24.877 INFO (MainThread) [homeassistant.components.select] Setting up select.huawei_solar
2022-12-24 12:32:24.881 INFO (MainThread) [custom_components.huawei_solar.number] Skipping number setup, as parameter configuration is not enabled
2022-12-24 12:32:24.882 INFO (MainThread) [custom_components.huawei_solar.switch] Skipping switch setup, as parameter configuration is not enabled
2022-12-24 12:32:24.882 INFO (MainThread) [custom_components.huawei_solar.select] Skipping select setup, as parameter configuration is not enabled
2022-12-24 12:32:24.888 WARNING (MainThread) [aioesphomeapi.reconnect_logic] Can't connect to ESPHome API for esp32-d1 @ 192.168.18.221: Error connecting to ('192.168.18.221', 6053): [Errno 113] Connect call failed ('192.168.18.221', 6053)
2022-12-24 12:32:24.897 INFO (MainThread) [aioesphomeapi.reconnect_logic] Trying to reconnect to esp32-d1 @ 192.168.18.221 in the background
2022-12-24 12:32:25.568 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 61.92s
2022-12-24 12:32:25.571 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2022-12-24 12:32:25.641 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: None has no element 0 when rendering '{{ state_attr('switch.sonoff_100123b0a9', 'consumption').0 }}'
2022-12-24 12:32:25.661 WARNING (MainThread) [homeassistant.helpers.template] Template variable warning: None has no element 0 when rendering '{{ state_attr('switch.sonoff_100123b0a9', 'consumption').0 }}'
2022-12-24 12:32:25.683 ERROR (MainThread) [homeassistant.helpers.event] Error while processing template: Template("{% if states('sensor.grid_active_power') | int > 0 %}
  {{ states('sensor.grid_active_power') }}
{% else -%}
  0
{% endif %}")
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 423, in async_render
    render_result = _render_with_context(self.template, compiled, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1942, in _render_with_context
    return template.render(**kwargs)
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 1301, in render
    self.environment.handle_exception()
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 936, in handle_exception
    raise rewrite_traceback_stack(source=source)
  File "<template>", line 1, in top-level template code
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1729, in forgiving_int_filter
    raise_no_default("int", value)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1411, in raise_no_default
    raise ValueError(
ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  {{ states('sensor.grid_active_power') }}
{% else -%}
  0
{% endif %}' but no default was specified

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

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 540, in async_render_to_info
    render_info._result = self.async_render(variables, strict=strict, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 425, in async_render
    raise TemplateError(err) from err
homeassistant.exceptions.TemplateError: ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  {{ states('sensor.grid_active_power') }}
{% else -%}
  0
{% endif %}' but no default was specified
2022-12-24 12:32:25.690 ERROR (MainThread) [homeassistant.helpers.template_entity] TemplateError('ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  {{ states('sensor.grid_active_power') }}
{% else -%}
  0
{% endif %}' but no default was specified') while processing template 'Template("{% if states('sensor.grid_active_power') | int > 0 %}
  {{ states('sensor.grid_active_power') }}
{% else -%}
  0
{% endif %}")' for attribute '_attr_native_value' in entity 'sensor.template_grid_feed_in'
2022-12-24 12:32:25.701 ERROR (MainThread) [homeassistant.helpers.event] Error while processing template: Template("{% if states('sensor.grid_active_power') | int > 0 %}
  0
{% else -%}
  {{ (states('sensor.grid_active_power') | int) | abs }} 
{% endif %}")
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 423, in async_render
    render_result = _render_with_context(self.template, compiled, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1942, in _render_with_context
    return template.render(**kwargs)
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 1301, in render
    self.environment.handle_exception()
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 936, in handle_exception
    raise rewrite_traceback_stack(source=source)
  File "<template>", line 1, in top-level template code
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1729, in forgiving_int_filter
    raise_no_default("int", value)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1411, in raise_no_default
    raise ValueError(
ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  0
{% else -%}
  {{ (states('sensor.grid_active_power') | int) | abs }} 
{% endif %}' but no default was specified

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

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 540, in async_render_to_info
    render_info._result = self.async_render(variables, strict=strict, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 425, in async_render
    raise TemplateError(err) from err
homeassistant.exceptions.TemplateError: ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  0
{% else -%}
  {{ (states('sensor.grid_active_power') | int) | abs }} 
{% endif %}' but no default was specified
2022-12-24 12:32:25.722 ERROR (MainThread) [homeassistant.helpers.template_entity] TemplateError('ValueError: Template error: int got invalid input 'unavailable' when rendering template '{% if states('sensor.grid_active_power') | int > 0 %}
  0
{% else -%}
  {{ (states('sensor.grid_active_power') | int) | abs }} 
{% endif %}' but no default was specified') while processing template 'Template("{% if states('sensor.grid_active_power') | int > 0 %}
  0
{% else -%}
  {{ (states('sensor.grid_active_power') | int) | abs }} 
{% endif %}")' for attribute '_attr_native_value' in entity 'sensor.template_grid_consumption'
2022-12-24 12:32:25.727 ERROR (MainThread) [homeassistant.helpers.event] Error while processing template: Template("{% if states('sensor.template_grid_consumption') | float > 0 %}
  {{ states('sensor.active_power') }}
{% else -%}
  {{ (states('sensor.active_power')| float - states('sensor.template_grid_feed_in')| float) }}
{% endif %}")
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1710, in forgiving_float_filter
    return float(value)
ValueError: could not convert string to float: 'unavailable'

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 423, in async_render
    render_result = _render_with_context(self.template, compiled, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1942, in _render_with_context
    return template.render(**kwargs)
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 1301, in render
    self.environment.handle_exception()
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 936, in handle_exception
    raise rewrite_traceback_stack(source=source)
  File "<template>", line 1, in top-level template code
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1713, in forgiving_float_filter
    raise_no_default("float", value)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1411, in raise_no_default
    raise ValueError(
ValueError: Template error: float got invalid input 'unavailable' when rendering template '{% if states('sensor.template_grid_consumption') | float > 0 %}
  {{ states('sensor.active_power') }}
{% else -%}
  {{ (states('sensor.active_power')| float - states('sensor.template_grid_feed_in')| float) }}
{% endif %}' but no default was specified

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

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 540, in async_render_to_info
    render_info._result = self.async_render(variables, strict=strict, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 425, in async_render
    raise TemplateError(err) from err
homeassistant.exceptions.TemplateError: ValueError: Template error: float got invalid input 'unavailable' when rendering template '{% if states('sensor.template_grid_consumption') | float > 0 %}
  {{ states('sensor.active_power') }}
{% else -%}
  {{ (states('sensor.active_power')| float - states('sensor.template_grid_feed_in')| float) }}
{% endif %}' but no default was specified
2022-12-24 12:32:25.733 ERROR (MainThread) [homeassistant.helpers.template_entity] TemplateError('ValueError: Template error: float got invalid input 'unavailable' when rendering template '{% if states('sensor.template_grid_consumption') | float > 0 %}
  {{ states('sensor.active_power') }}
{% else -%}
  {{ (states('sensor.active_power')| float - states('sensor.template_grid_feed_in')| float) }}
{% endif %}' but no default was specified') while processing template 'Template("{% if states('sensor.template_grid_consumption') | float > 0 %}
  {{ states('sensor.active_power') }}
{% else -%}
  {{ (states('sensor.active_power')| float - states('sensor.template_grid_feed_in')| float) }}
{% endif %}")' for attribute '_attr_native_value' in entity 'sensor.template_curent_solar_consumption'
2022-12-24 12:32:25.746 ERROR (MainThread) [homeassistant.helpers.event] Error while processing template: Template("{% set uptime = states.sensor.openwrt_router_uptime.state | int %} {% set days = (uptime / 86400) | int %} {%- if days > 0 -%}
  {{ days }} days, {{ (uptime - (days * 86400)) | int | timestamp_custom('%H:%M:%S', false) }}
{%- else -%}
  {{ uptime | int | timestamp_custom('%H:%M:%S', false) }}
{%- endif -%}")
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 423, in async_render
    render_result = _render_with_context(self.template, compiled, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1942, in _render_with_context
    return template.render(**kwargs)
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 1301, in render
    self.environment.handle_exception()
  File "/usr/local/lib/python3.10/site-packages/jinja2/environment.py", line 936, in handle_exception
    raise rewrite_traceback_stack(source=source)
  File "<template>", line 1, in top-level template code
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 1727, in forgiving_int_filter
    result = jinja2.filters.do_int(value, default=default, base=base)
  File "/usr/local/lib/python3.10/site-packages/jinja2/filters.py", line 957, in do_int
    return int(value)
jinja2.exceptions.UndefinedError: 'None' has no attribute 'state'

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

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 540, in async_render_to_info
    render_info._result = self.async_render(variables, strict=strict, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/template.py", line 425, in async_render
    raise TemplateError(err) from err
homeassistant.exceptions.TemplateError: UndefinedError: 'None' has no attribute 'state'
2022-12-24 12:32:25.757 ERROR (MainThread) [homeassistant.helpers.template_entity] TemplateError('UndefinedError: 'None' has no attribute 'state'') while processing template 'Template("{% set uptime = states.sensor.openwrt_router_uptime.state | int %} {% set days = (uptime / 86400) | int %} {%- if days > 0 -%}
  {{ days }} days, {{ (uptime - (days * 86400)) | int | timestamp_custom('%H:%M:%S', false) }}
{%- else -%}
  {{ uptime | int | timestamp_custom('%H:%M:%S', false) }}
{%- endif -%}")' for attribute '_attr_native_value' in entity 'sensor.openwrt_uptime'
2022-12-24 12:32:25.794 INFO (MainThread) [custom_components.hacs] Stage changed: startup
2022-12-24 12:32:25.996 INFO (MainThread) [custom_components.hacs] Loading removed repositories

yousaf465 commented 1 year ago

tried deleting and readding using dongle's ip

Using Inverters' Ip

connected via dongle's ip an slave 1 but only three

wlcrs commented 1 year ago

Thank you for the logs, this helped me eliminate a lot of potential problems.

I've found and fixed a potential issue. Please upgrade to 1.2.0 beta 3 and test again.

(@peros550 I didn't want to sound harsh. I'm sorry if you felt personally attacked. Communicating in written text is always hard to express intent in that regard.)

dMopp commented 1 year ago

Ah thats Fine.

the Classic +1/metoo comment is more like a „make it visible“ thing for the ppl why having logs is crucial for the devs :) GitHub is bad in allowing to distinguish between „rare bug“ and „common issue“)

yousaf465 commented 1 year ago

Thank you for the logs, this helped me eliminate a lot of potential problems.

I've found and fixed a potential issue. Please upgrade to 1.2.0 beta 3 and test again.

(@peros550 I didn't want to sound harsh. I'm sorry if you felt personally attacked. Communicating in written text is always hard to express intent in that regard.)

Entities named changed which messed up the sensors. Waiting for 1.2.0 beta 3 to fix this issue so that I can connect via inverter.

peros550 commented 1 year ago

@wlcrs 👍new version works. Many thanks!

By the way, couldn't find the Inverter ON/OFF switch. I was expecting it to be added in the room's view together with the switches that are already present.

yousaf465 commented 1 year ago

@wlcrs 👍new version works. Many thanks!

By the way, couldn't find the Inverter ON/OFF switch. I was expecting it to be added in the room's view together with the switches that are already present.

it is under inverter > configuration >

I haven't got the update yet waiting for the 1.2.0 beta 3 update.

peros550 commented 1 year ago

For me it’s not there. Maybe it requires elevated permissions during the setup ?

wlcrs commented 1 year ago

For me it’s not there. Maybe it requires elevated permissions during the setup ?

Yes

yousaf465 commented 1 year ago

For me it’s not there. Maybe it requires elevated permissions during the setup ?

Yes

to fix the new name scheme issue I tried connecting via inverter but this happened using 1.2.0b3

Ip 192.168.200.1 Port 6607 slave id 0 advanced checked

wlcrs / huawei_solar