Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

SMA scrape stop working in version 2024.4.4 #116289

Closed
jayjayvm opened this issue Apr 27, 2024 · 26 comments
Closed

SMA scrape stop working in version 2024.4.4 #116289

jayjayvm opened this issue Apr 27, 2024 · 26 comments

Comments

@jayjayvm
Copy link

The problem

SMA scrape or modbus stopt working in core 2024.4.4 operating system 12.2. No values are parsed anymore.

What version of Home Assistant Core has the issue?

2024.4.4

What was the last working version of Home Assistant Core?

2024.4.3

What type of installation are you running?

Home Assistant OS

Integration causing the issue

SMA scrape

Link to integration documentation on our website

No response

Diagnostics information

No response

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

No response

@mib1185
Copy link
Contributor

mib1185 commented Apr 27, 2024

Please provide a link to the documentation of the used integration. Further also check and provide log messages, thx 👍

@jayjayvm
Copy link
Author

jayjayvm commented Apr 27, 2024

Sorry to say, it is the old way to use MODBUS not an integration as such there is one of MODBUS but that one never worked for my SMA inverter.

A snippet of my configuration is:

`modbus:

  • name: SMA
    type: tcp
    host: 192.168.0.xxx
    port: 502
    sensors:
    • name: SMA_Power_AC
      unique_id: SMA_Power_AC
      state_class: measurement
      device_class: "power"
      unit_of_measurement: W
      slave: 3
      address: 30775
      data_type: uint32
      scan_interval: 10
    • name: SMA_PV_Daily_Yield
      unique_id: SMA_PV_Daily_Yield
      unit_of_measurement: kWh
      state_class: measurement
      device_class: "energy"
      slave: 3
      address: 30517
      scan_interval: 60
      scale: 0.001
      precision: 3`

@home-assistant
Copy link

Hey there @janiversen, mind taking a look at this issue as it has been labeled with an integration (modbus) you are listed as a code owner for? Thanks!

Code owner commands

Code owners of modbus can trigger bot actions by commenting:

  • @home-assistant close Closes the issue.
  • @home-assistant rename Awesome new title Renames the issue.
  • @home-assistant reopen Reopen the issue.
  • @home-assistant unassign modbus Removes the current integration label and assignees on the issue, add the integration domain after the command.
  • @home-assistant add-label needs-more-information Add a label (needs-more-information, problem in dependency, problem in custom component) to the issue.
  • @home-assistant remove-label needs-more-information Remove a label (needs-more-information, problem in dependency, problem in custom component) on the issue.

(message by CodeOwnersMention)


modbus documentation
modbus source
(message by IssueLinks)

@mib1185
Copy link
Contributor

mib1185 commented Apr 28, 2024

Pleas still check and provide some logs, thx

@janiversen
Copy link
Member

Modbus is a proper integration ! and this is the only modbus integration in Home assistant, there are other integrations that uses it, like a custom component for SMA.

Please provide a debug log as pr modbus integration documentation. It would also be nice to know what does not work.....saying it stopped working is not really helpful for anyone.

And now to the funny part, there are no modbus changes between 2024.4.3 and 2024.4.4 as far as I know.

@janiversen
Copy link
Member

And please remember to remove all custom components before making the debug log as pr instructions in the issue template.

@jayjayvm
Copy link
Author

As said, for some older SMA inverters (12 years), does the integration not function, it will never make contact with the more simple webcon of the device. Therefore was the above way necessary described above with code in the configuration.yaml. It also does mean that there is no log, for this way, only the official for me not working integration gives out a log.

I highly appreciate your time and effort you put into answering me, thanks.

@jayjayvm
Copy link
Author

O I found a way to get a log, log output:

`Logger: homeassistant.components.modbus.modbus
Source: components/modbus/modbus.py:318
integration: Modbus (documentation, issues)
First occurred: 12:14:40 PM (1 occurrences)
Last logged: 12:14:40 PM

Pymodbus: SMA: Error: device: 3 address: 30775 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.0.xxx:502]`

Hope that helps.

@janiversen
Copy link
Member

No I need a debug log as pr modbus integration documentation. The log above only tells your device is not connected.

I am not sure which integration you are referring to, that do not work but it does not seem to be a HA integration and therefore not something we consider in this channel.

@jayjayvm
Copy link
Author

I hope you might come up with some sort of idea how to fix it Jan, I understand this is not the forum. I can not get any other debug information nor reason why it stopt working after the update to core 2024.4.4. It is an official MODBUS integration as it has been before the enity add on for MODBUS as can be seen in the link you gave me: https://www.home-assistant.io/integrations/modbus.
I thank you for the time you spend on trying to figure out what might be the case and answering. Hopefully it will be restored in a future update else I can erase number 6 of integrations that went out of the door after an update.

@janiversen
Copy link
Member

Without a debug log I will nit investigate further but simply conclude that even though there might be a bug, you did not want to help. Making a debug log is a simple task and the least you can do if you want us to use our time on your problem.

@janiversen janiversen closed this as not planned Won't fix, can't repro, duplicate, stale Apr 28, 2024
@jayjayvm
Copy link
Author

Sorry I have no clue howto get a debug log from MODBUS. I did change configuration. yaml debug options, to get the error I got. But in the systems tab log there is not a MODBUS log! No clue how to get a debug, if I could I have send that to you immediately for help for this is certainly a problem i love to get resolved, for my energy dash is corrupted now, my temperature readings for the cooling automation of the inverter is corrupted, do I want a solution, well most definitely.

@mib1185
Copy link
Contributor

mib1185 commented Apr 28, 2024

there is no separate log for modbus only, but the debug logs are contained in the central homeassistent log - as all integration logs could be found there.

@janiversen
Copy link
Member

As noted in the modbus integration documentation, there are NO talk of a separate log, just the homeassistant.log !!

If you added the log options correctly, the log contains a lot more than the error (which you also got without the debug log), it contains all modbus communication.

@SonnyBBQ
Copy link

Hi @janiversen I seem to have the same issue with my SMA inverter. So let's see if I can get you the necessary info.

What version of Home Assistant Core has the issue?
2024.4 onwards

What was the last working version of Home Assistant Core?
2024.3.3

What type of installation are you running?
Home Assistant OS

Integration causing the issue
Modbus

Link to integration documentation on our website
No response

Diagnostics information
No response

Example YAML snippet

modbus:
  - name: SMA
    type: tcp
    host: 192.168.1.223 #fixed ip in SMA  192.168.1.223
    port: 502
    sensors:
      - name: SMA_Power_AC
        unit_of_measurement: W
        slave: 3
        address: 30775
        data_type: int32
      - name: PV Daily Yield
        unit_of_measurement: kWh
        slave: 3
        address: 30517
        scale: 0.001
        precision: 3
        data_type: uint64
      - name: PV Total Production
        unit_of_measurement: MWh
        slave: 3
        address: 30513
        scale: 0.000001
        precision: 3
        data_type: uint64
      - name: Grid Voltage
        unit_of_measurement: V
        slave: 3
        address: 30783
        scale: 0.01
        precision: 2
        data_type: uint32
      - name: Grid frequency
        unit_of_measurement: Hz
        slave: 3
        address: 30803
        scale: 0.01
        precision: 2
        data_type: uint32
      - name: SMA_temp
        unit_of_measurement: °C
        slave: 3
        address: 30953
        scale: 0.1
        precision: 1
        data_type: int32
      - name: SMA_status
        slave: 3
        address: 30201
        data_type: uint32
      - name: SMA_grid
        slave: 3
        address: 30217
        data_type: uint32
      - name: Insulation
        slave: 3
        address: 30225
        scale: 0.001
        unit_of_measurement: kOhms
        data_type: uint32
      - name: SMA_Residual_current
        slave: 3
        address: 31247
        scale: 1
        unit_of_measurement: mA
        data_type: int32
      - name: Apparent Power
        unit_of_measurement: VA
        slave: 3
        address: 30813
        scale: 1
        precision: 0
        data_type: int32
      - name: Reactive Power
        unit_of_measurement: VAr
        slave: 3
        address: 30805
        scale: 1
        precision: 0
        data_type: int32

Anything in the logs that might be useful for us?

2024-04-30 09:02:26.711 WARNING (MainThread) [pymodbus.logging] Failed to connect 
2024-04-30 09:02:26.711 INFO (MainThread) [homeassistant.components.modbus.modbus] modbus SMA communication open
2024-04-30 09:02:27.372 ERROR (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30775 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.372 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30517 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.373 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30513 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.373 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30783 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.381 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30803 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.381 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30953 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.381 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30201 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.381 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30217 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.381 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30225 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.382 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 31247 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.382 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30813 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]
2024-04-30 09:02:27.382 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA: Error: device: 3 address: 30805 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 192.168.1.223:502]

ValueError: Sensor sensor.modbus_sma_pv_power has device class 'None', state class 'None' unit 'W' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'unavailable' (<class 'str'>)
2024-04-30 09:02:53.982 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aac720>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'unavailable'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_sma_pv_apparent_power has device class 'None', state class 'None' unit 'VA' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'unavailable' (<class 'str'>)
2024-04-30 09:02:53.990 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aac9a0>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'unavailable'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_sma_pv_reactive_power has device class 'None', state class 'None' unit 'VAr' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'unavailable' (<class 'str'>)
2024-04-30 09:02:53.996 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aacc20>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'unavailable'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_sma_pv_residual has device class 'None', state class 'None' unit 'mA' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'unavailable' (<class 'str'>)
2024-04-30 09:02:54.003 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aacea0>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'unavailable'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_sma_temperature has device class 'None', state class 'None' unit '°C' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'unavailable' (<class 'str'>)
2024-04-30 09:02:54.008 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aad120>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'Not Measured'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_grid_frequency has device class 'None', state class 'None' unit 'Hz' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'Not Measured' (<class 'str'>)
2024-04-30 09:02:54.013 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback <_OneTimeListener homeassistant.helpers.start:<function _async_at_core_state.<locals>._matched_event at 0x7f9b85aad440>>(<Event homeassistant_start[L]>)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 668, in state
    numerical_value = float(value)  # type:ignore[arg-type]
                      ^^^^^^^^^^^^
ValueError: could not convert string to float: 'Not Measured'

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.12/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/usr/src/homeassistant/homeassistant/core.py", line 1322, in __call__
    self.hass.async_run_hass_job(self.listener_job, event)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/helpers/start.py", line 41, in _matched_event
    hass.async_run_hass_job(at_start_job, hass)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 491, in _async_template_startup
    result_info.async_refresh()
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1075, in async_refresh
    self._refresh(None)
  File "/usr/src/homeassistant/homeassistant/helpers/event.py", line 1261, in _refresh
    self.hass.async_run_hass_job(self._job, event, updates)
  File "/usr/src/homeassistant/homeassistant/core.py", line 837, in async_run_hass_job
    hassjob.target(*args)
  File "/usr/src/homeassistant/homeassistant/components/template/template_entity.py", line 439, in _handle_results
    self.async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 998, in async_write_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1119, in _async_write_ha_state
    state, attr, capabilities, shadowed_attr = self.__async_calculate_state()
                                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1056, in __async_calculate_state
    state = self._stringify_state(available)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 1004, in _stringify_state
    if (state := self.state) is None:
                 ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/sensor/__init__.py", line 670, in state
    raise ValueError(
ValueError: Sensor sensor.modbus_grid_voltage has device class 'None', state class 'None' unit 'V' and suggested precision 'None' thus indicating it has a numeric value; however, it has the non-numeric value: 'Not Measured' (<class 'str'>)

Additional information
Resetting the inverter will make the connection work for a limited time only. Couple hours at most.

@florie1706
Copy link

Same here, SMA inverter of 2011, always worked fine. now every couple of days no new data until power off the breaker and restart HA

It is working now, but here is the log:

2024-05-09 21:36:18.695 DEBUG (MainThread) [pymodbus.logging] Adding transaction 43
2024-05-09 21:36:18.695 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:18.695 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x2b 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x41 0x0 0x2
2024-05-09 21:36:18.835 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x2b 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a old_data:  addr=None
2024-05-09 21:36:18.835 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2b 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a
2024-05-09 21:36:18.836 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 12, MBAP len 7 !!
2024-05-09 21:36:18.836 DEBUG (MainThread) [pymodbus.logging] recv: 0x88 old_data:  addr=None
2024-05-09 21:36:18.836 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2b 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a 0x88
2024-05-09 21:36:18.836 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:18.837 DEBUG (MainThread) [pymodbus.logging] Getting transaction 43
2024-05-09 21:36:18.837 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:18.837 DEBUG (MainThread) [pymodbus.logging] Adding transaction 44
2024-05-09 21:36:18.837 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:18.838 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x2c 0x0 0x0 0x0 0x6 0x3 0x3 0x78 0x37 0x0 0x2
2024-05-09 21:36:18.985 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x2c 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0 old_data:  addr=None
2024-05-09 21:36:18.985 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2c 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0
2024-05-09 21:36:18.986 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 12, MBAP len 7 !!
2024-05-09 21:36:18.986 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 old_data:  addr=None
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2c 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0 0x0
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Getting transaction 44
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Adding transaction 45
2024-05-09 21:36:18.987 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:18.988 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x2d 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x35 0x0 0x4
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x2d 0x0 0x0 0x0 0xb 0x3 0x3 0x8 0x0 0x0 0x0 0x0 0x0 0x0 0xa6 old_data:  addr=None
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2d 0x0 0x0 0x0 0xb 0x3 0x3 0x8 0x0 0x0 0x0 0x0 0x0 0x0 0xa6
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 16, MBAP len 11 !!
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] recv: 0x3e old_data:  addr=None
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2d 0x0 0x0 0x0 0xb 0x3 0x3 0x8 0x0 0x0 0x0 0x0 0x0 0x0 0xa6 0x3e
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:19.135 DEBUG (MainThread) [pymodbus.logging] Getting transaction 45
2024-05-09 21:36:19.136 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:28.695 DEBUG (MainThread) [pymodbus.logging] Adding transaction 46
2024-05-09 21:36:28.695 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:28.695 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x2e 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x41 0x0 0x2
2024-05-09 21:36:28.735 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x2e 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a old_data:  addr=None
2024-05-09 21:36:28.736 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2e 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a
2024-05-09 21:36:28.736 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 12, MBAP len 7 !!
2024-05-09 21:36:28.737 DEBUG (MainThread) [pymodbus.logging] recv: 0x88 old_data:  addr=None
2024-05-09 21:36:28.737 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2e 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a 0x88
2024-05-09 21:36:28.738 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:28.738 DEBUG (MainThread) [pymodbus.logging] Getting transaction 46
2024-05-09 21:36:28.738 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:28.738 DEBUG (MainThread) [pymodbus.logging] Adding transaction 47
2024-05-09 21:36:28.738 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:28.739 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x2f 0x0 0x0 0x0 0x6 0x3 0x3 0x78 0x37 0x0 0x2
2024-05-09 21:36:28.885 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x2f 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0 old_data:  addr=None
2024-05-09 21:36:28.885 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2f 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0
2024-05-09 21:36:28.885 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 12, MBAP len 7 !!
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 old_data:  addr=None
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x2f 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x80 0x0 0x0 0x0
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] Getting transaction 47
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:28.886 DEBUG (MainThread) [pymodbus.logging] Adding transaction 48
2024-05-09 21:36:28.887 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:28.887 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x30 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x35 0x0 0x4
2024-05-09 21:36:29.241 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x30 0x0 0x0 0x0 0xb 0x3 0x3 0x8 0x0 0x0 0x0 0x0 0x0 0x0 0xa6 0x3e old_data:  addr=None
2024-05-09 21:36:29.241 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x30 0x0 0x0 0x0 0xb 0x3 0x3 0x8 0x0 0x0 0x0 0x0 0x0 0x0 0xa6 0x3e
2024-05-09 21:36:29.241 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:29.241 DEBUG (MainThread) [pymodbus.logging] Getting transaction 48
2024-05-09 21:36:29.241 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-09 21:36:38.696 DEBUG (MainThread) [pymodbus.logging] Adding transaction 49
2024-05-09 21:36:38.696 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-09 21:36:38.696 DEBUG (MainThread) [pymodbus.logging] send: 0x0 0x31 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x41 0x0 0x2
2024-05-09 21:36:38.786 DEBUG (MainThread) [pymodbus.logging] recv: 0x0 0x31 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a old_data:  addr=None
2024-05-09 21:36:38.786 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x31 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a
2024-05-09 21:36:38.786 DEBUG (MainThread) [pymodbus.logging] Frame check failed, missing part of message len 12, MBAP len 7 !!
2024-05-09 21:36:38.787 DEBUG (MainThread) [pymodbus.logging] recv: 0x88 old_data:  addr=None
2024-05-09 21:36:38.787 DEBUG (MainThread) [pymodbus.logging] Processing: 0x0 0x31 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x8 0xa 0x5a 0x88
2024-05-09 21:36:38.787 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-09 21:36:38.787 DEBUG (MainThread) [pymodbus.logging] Getting transaction 49
2024-05-09 21:36:38.787 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!

@janiversen
Copy link
Member

We need a debug log showing the problem!

@florie1706
Copy link

florie1706 commented May 10, 2024

Ok, thought “frame check failed” is maybe already a hint 😉 So I wait until no new data is received and post the debug log then. Thanks

@janiversen
Copy link
Member

janiversen commented May 10, 2024

No need to, just checked the sma code, it does not use the modbus integration so you need to talk to a sma maintainer. (sma uses pysma instead of modbus).

Changing labels and unsubscribing

@florie1706
Copy link

@janiversen I do not use the sma integration but directly the modbus integration. See the config above. It starts with modbus …

@janiversen
Copy link
Member

Then it has nothing to do with this issue, which is about the sma integration.

@SonnyBBQ
Copy link

Same thing here. I'm using Modbus since I never got the SMA integration to connect. Using the Modbus integration with my SMA inverter had been rock solid for years.

Would there be anything else you need instead of above debug log?

@SonnyBBQ
Copy link

Hi @janiversen

I believe the OP misnamed this. Looking at his yaml, he is using Modbus as well. If it helps, and as this was closed, I had opened a new issue for this under modbus. #116683

@florie1706
Copy link

Hi @janiversen now the error came back, so here is my debug log.

2024-05-15 09:33:19.047 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:19.047 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xc7 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8b 0x0 0x2
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xc7 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xc7 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24775
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24776
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:19.172 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xc8 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8d 0x0 0x2
2024-05-15 09:33:19.296 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xc8 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:19.297 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xc8 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:19.297 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:19.297 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24776
2024-05-15 09:33:19.297 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:19.375 WARNING (MainThread) [pymodbus.logging] Failed to connect 
2024-05-15 09:33:19.376 DEBUG (MainThread) [pymodbus.logging] Wait comm 200.0 ms before reconnecting.
2024-05-15 09:33:19.576 DEBUG (MainThread) [pymodbus.logging] Connecting to 10.10.10.11:502.
2024-05-15 09:33:19.577 DEBUG (MainThread) [pymodbus.logging] Connecting comm
2024-05-15 09:33:20.514 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30529 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:20.514 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30775 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:20.514 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30517 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:22.578 WARNING (MainThread) [pymodbus.logging] Failed to connect 
2024-05-15 09:33:22.578 DEBUG (MainThread) [pymodbus.logging] Wait comm 200.0 ms before reconnecting.
2024-05-15 09:33:22.779 DEBUG (MainThread) [pymodbus.logging] Connecting to 10.10.10.11:502.
2024-05-15 09:33:22.780 DEBUG (MainThread) [pymodbus.logging] Connecting comm
2024-05-15 09:33:23.309 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24777
2024-05-15 09:33:23.310 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.310 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xc9 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xba 0x0 0x2
2024-05-15 09:33:23.312 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15324
2024-05-15 09:33:23.312 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.312 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xdc 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xba 0x0 0x2
2024-05-15 09:33:23.415 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xdc 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x41 0x50 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.416 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xdc 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x41 0x50 0x0 0x0
2024-05-15 09:33:23.416 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.416 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15324
2024-05-15 09:33:23.416 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.417 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15325
2024-05-15 09:33:23.417 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.417 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xdd 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x58 0x0 0x2
2024-05-15 09:33:23.422 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xc9 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.422 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xc9 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0
2024-05-15 09:33:23.422 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.422 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24777
2024-05-15 09:33:23.422 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.423 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24778
2024-05-15 09:33:23.423 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.423 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xca 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x58 0x0 0x2
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xdd 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xdd 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15325
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15326
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.540 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xde 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xbf 0x0 0x1
2024-05-15 09:33:23.546 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xca 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.546 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xca 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.546 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.547 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24778
2024-05-15 09:33:23.547 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.547 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24779
2024-05-15 09:33:23.547 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.547 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xcb 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xbf 0x0 0x1
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xde 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1 old_data:  addr=None
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xde 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15326
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15327
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.665 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xdf 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xb1 0x0 0x5
2024-05-15 09:33:23.671 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xcb 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1 old_data:  addr=None
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xcb 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24779
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24780
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.672 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xcc 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xb1 0x0 0x5
2024-05-15 09:33:23.791 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xdf 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.791 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xdf 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.791 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.791 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15327
2024-05-15 09:33:23.791 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.792 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15328
2024-05-15 09:33:23.792 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.792 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe0 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x76 0x0 0x4
2024-05-15 09:33:23.797 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xcc 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xcc 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24780
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24781
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.798 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xcd 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x76 0x0 0x4
2024-05-15 09:33:23.916 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe0 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x4f 0xd1 0x96 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.916 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe0 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x4f 0xd1 0x96 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.916 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.916 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15328
2024-05-15 09:33:23.916 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.922 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xcd 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x1b 0x14 0x84 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xcd 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x1b 0x14 0x84 0x0 0x0 0x0 0x0
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24781
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24782
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:23.923 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xce 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x89 0x0 0x2
2024-05-15 09:33:24.047 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xce 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xce 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0x0 0x0 0x0
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24782
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24783
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:24.048 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xcf 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8b 0x0 0x2
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xcf 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xcf 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24783
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24784
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:24.173 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd0 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8d 0x0 0x2
2024-05-15 09:33:24.297 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd0 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:24.298 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd0 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:24.298 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:24.298 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24784
2024-05-15 09:33:24.298 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:24.934 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24785
2024-05-15 09:33:24.935 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:24.935 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd1 0x0 0x0 0x0 0xb 0xc8 0x10 0x5 0x89 0x0 0x2 0x4 0x40 0x0 0x0 0x0
2024-05-15 09:33:25.048 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd1 0x0 0x0 0x0 0x6 0xc8 0x10 0x5 0x89 0x0 0x2 old_data:  addr=None
2024-05-15 09:33:25.048 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd1 0x0 0x0 0x0 0x6 0xc8 0x10 0x5 0x89 0x0 0x2
2024-05-15 09:33:25.048 DEBUG (MainThread) [pymodbus.logging] Factory Response[WriteMultipleRegistersResponse': 16]
2024-05-15 09:33:25.048 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24785
2024-05-15 09:33:25.048 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:25.049 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24786
2024-05-15 09:33:25.049 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:25.049 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd2 0x0 0x0 0x0 0xb 0x1 0x10 0x4 0xba 0x0 0x2 0x4 0x40 0xa0 0x0 0x0
2024-05-15 09:33:25.173 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd2 0x0 0x0 0x0 0x6 0x1 0x10 0x4 0xba 0x0 0x2 old_data:  addr=None
2024-05-15 09:33:25.173 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd2 0x0 0x0 0x0 0x6 0x1 0x10 0x4 0xba 0x0 0x2
2024-05-15 09:33:25.173 DEBUG (MainThread) [pymodbus.logging] Factory Response[WriteMultipleRegistersResponse': 16]
2024-05-15 09:33:25.173 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24786
2024-05-15 09:33:25.173 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:25.176 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15329
2024-05-15 09:33:25.176 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:25.176 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe1 0x0 0x0 0x0 0xb 0xc8 0x10 0x5 0x89 0x0 0x2 0x4 0x40 0x0 0x0 0x0
2024-05-15 09:33:25.299 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe1 0x0 0x0 0x0 0x6 0xc8 0x10 0x5 0x89 0x0 0x2 old_data:  addr=None
2024-05-15 09:33:25.299 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe1 0x0 0x0 0x0 0x6 0xc8 0x10 0x5 0x89 0x0 0x2
2024-05-15 09:33:25.299 DEBUG (MainThread) [pymodbus.logging] Factory Response[WriteMultipleRegistersResponse': 16]
2024-05-15 09:33:25.300 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15329
2024-05-15 09:33:25.300 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:25.302 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15330
2024-05-15 09:33:25.302 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:25.302 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe2 0x0 0x0 0x0 0xb 0x1 0x10 0x4 0xba 0x0 0x2 0x4 0x40 0xa0 0x0 0x0
2024-05-15 09:33:25.424 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe2 0x0 0x0 0x0 0x6 0x1 0x10 0x4 0xba 0x0 0x2 old_data:  addr=None
2024-05-15 09:33:25.424 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe2 0x0 0x0 0x0 0x6 0x1 0x10 0x4 0xba 0x0 0x2
2024-05-15 09:33:25.424 DEBUG (MainThread) [pymodbus.logging] Factory Response[WriteMultipleRegistersResponse': 16]
2024-05-15 09:33:25.424 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15330
2024-05-15 09:33:25.424 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:25.781 WARNING (MainThread) [pymodbus.logging] Failed to connect 
2024-05-15 09:33:25.781 DEBUG (MainThread) [pymodbus.logging] Wait comm 200.0 ms before reconnecting.
2024-05-15 09:33:25.982 DEBUG (MainThread) [pymodbus.logging] Connecting to 10.10.10.11:502.
2024-05-15 09:33:25.982 DEBUG (MainThread) [pymodbus.logging] Connecting comm
2024-05-15 09:33:28.310 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24787
2024-05-15 09:33:28.311 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.311 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd3 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xba 0x0 0x2
2024-05-15 09:33:28.312 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15331
2024-05-15 09:33:28.312 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.312 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe3 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xba 0x0 0x2
2024-05-15 09:33:28.423 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd3 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.423 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd3 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0
2024-05-15 09:33:28.424 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.424 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24787
2024-05-15 09:33:28.424 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.425 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe3 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.425 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe3 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x40 0xa0 0x0 0x0
2024-05-15 09:33:28.425 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.425 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15331
2024-05-15 09:33:28.426 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.426 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24788
2024-05-15 09:33:28.426 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.426 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd4 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x58 0x0 0x2
2024-05-15 09:33:28.436 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15332
2024-05-15 09:33:28.436 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.436 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe4 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x58 0x0 0x2
2024-05-15 09:33:28.548 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd4 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.548 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd4 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.549 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.549 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24788
2024-05-15 09:33:28.549 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.550 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe4 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.550 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe4 0x0 0x0 0x0 0x7 0x1 0x3 0x4 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.550 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.550 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15332
2024-05-15 09:33:28.551 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.551 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24789
2024-05-15 09:33:28.551 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.551 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd5 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xbf 0x0 0x1
2024-05-15 09:33:28.553 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15333
2024-05-15 09:33:28.553 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.553 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe5 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xbf 0x0 0x1
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1 old_data:  addr=None
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24789
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24790
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.673 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd6 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xb1 0x0 0x5
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1 old_data:  addr=None
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe5 0x0 0x0 0x0 0x5 0x1 0x3 0x2 0x0 0x1
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15333
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15334
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.674 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe6 0x0 0x0 0x0 0x6 0x1 0x3 0x4 0xb1 0x0 0x5
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd6 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd6 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24790
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24791
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.799 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd7 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x76 0x0 0x4
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe6 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe6 0x0 0x0 0x0 0xd 0x1 0x3 0xa 0x41 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15334
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Adding transaction 15335
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.800 DEBUG (MainThread) [pymodbus.logging] send: 0x3b 0xe7 0x0 0x0 0x0 0x6 0x1 0x3 0x1 0x76 0x0 0x4
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd7 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x1b 0x14 0x84 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd7 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x1b 0x14 0x84 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24791
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24792
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd8 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x89 0x0 0x2
2024-05-15 09:33:28.924 DEBUG (MainThread) [pymodbus.logging] recv: 0x3b 0xe7 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x4f 0xd1 0x96 0x0 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:28.925 DEBUG (MainThread) [pymodbus.logging] Processing: 0x3b 0xe7 0x0 0x0 0x0 0xb 0x1 0x3 0x8 0x41 0x4f 0xd1 0x96 0x0 0x0 0x0 0x0
2024-05-15 09:33:28.925 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:28.925 DEBUG (MainThread) [pymodbus.logging] Getting transaction 15335
2024-05-15 09:33:28.925 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:28.983 WARNING (MainThread) [pymodbus.logging] Failed to connect 
2024-05-15 09:33:28.983 DEBUG (MainThread) [pymodbus.logging] Wait comm 200.0 ms before reconnecting.
2024-05-15 09:33:29.049 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd8 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0x0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:29.049 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd8 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0x0 0x0 0x0
2024-05-15 09:33:29.050 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:29.050 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24792
2024-05-15 09:33:29.050 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:29.050 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24793
2024-05-15 09:33:29.050 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:29.051 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xd9 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8b 0x0 0x2
2024-05-15 09:33:29.174 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xd9 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:29.174 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xd9 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:29.174 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:29.174 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24793
2024-05-15 09:33:29.174 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:29.175 DEBUG (MainThread) [pymodbus.logging] Adding transaction 24794
2024-05-15 09:33:29.175 DEBUG (MainThread) [pymodbus.logging] Resetting frame - Current Frame in buffer - 
2024-05-15 09:33:29.175 DEBUG (MainThread) [pymodbus.logging] send: 0x60 0xda 0x0 0x0 0x0 0x6 0xc8 0x3 0x5 0x8d 0x0 0x2
2024-05-15 09:33:29.189 DEBUG (MainThread) [pymodbus.logging] Connecting to 10.10.10.11:502.
2024-05-15 09:33:29.189 DEBUG (MainThread) [pymodbus.logging] Connecting comm
2024-05-15 09:33:29.299 DEBUG (MainThread) [pymodbus.logging] recv: 0x60 0xda 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0 old_data:  addr=None
2024-05-15 09:33:29.300 DEBUG (MainThread) [pymodbus.logging] Processing: 0x60 0xda 0x0 0x0 0x0 0x7 0xc8 0x3 0x4 0x40 0xc0 0x0 0x0
2024-05-15 09:33:29.300 DEBUG (MainThread) [pymodbus.logging] Factory Response[ReadHoldingRegistersResponse': 3]
2024-05-15 09:33:29.300 DEBUG (MainThread) [pymodbus.logging] Getting transaction 24794
2024-05-15 09:33:29.300 DEBUG (MainThread) [pymodbus.logging] Frame check, no more data!
2024-05-15 09:33:30.515 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30529 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:30.515 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30775 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:30.515 DEBUG (MainThread) [homeassistant.components.modbus.modbus] Pymodbus: SMA_Wechselrichter: Error: device: 3 address: 30517 -> Modbus Error: [Connection] Not connected[AsyncModbusTcpClient 10.10.10.11:502]
2024-05-15 09:33:32.190 WARNING (MainThread) [pymodbus.logging] Failed to connect 

Looks like this connection error reappears every 10 seconds until I restart the inverter and HA

@janiversen
Copy link
Member

You device or something in between breaks the connection, this is not a bug in the integration

2024-05-15 09:33:19.375 WARNING (MainThread) [pymodbus.logging] Failed to connect
2024-05-15 09:33:19.376 DEBUG (MainThread) [pymodbus.logging] Wait comm 200.0 ms before reconnecting.
2024-05-15 09:33:19.576 DEBUG (MainThread) [pymodbus.logging] Connecting to 10.10.10.11:502.
2024-05-15 09:33:19.577 DEBUG (MainThread) [pymodbus.logging] Connecting comm

@florie1706
Copy link

It worked for 6 days now. And when this happens the connection won't come back unless put the breaker down from the inverter and fire it up again. Even then I need to restart HA to have it working again for a couple of days. Ping request to the inverter is 100% fine all the time. Never had this issue before. Did not change anything on hardware side. Why did it work with older HA versions for ages without any error?!? And I am not alone ...

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

5 participants