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

randomic HA response delay with ABB Integration #61

Closed
Claudio1L opened this issue Mar 6, 2023 · 59 comments
Closed

randomic HA response delay with ABB Integration #61

Claudio1L opened this issue Mar 6, 2023 · 59 comments

Comments

@Claudio1L
Copy link

Claudio1L commented Mar 6, 2023

Hi

after some weeks of troubleshooting, I'm pretty sure there is a problem with the integration. Let me describe:
When the sun goes down, the inverter switch off and home assistant is not able to reach the inverter, also via IP (ping).
In that phase starts a random delay in the HA responsivenessy. At least in my case it is easy to reproduce. It's enough to switch on/off a light, or a switch, repeatedly and after 4-5 cycles you can see that delay. That Light or switch does not respond for 2-3 secs and then start again switching fine, till the next 4-5 cycles.
I could imagine an issue when the abb integration try to reach the inverter (polling period) during the switch off phase.
Reloading HA or the Integration only, fix the issue because inverter is switched off and entities are removed.

I'm not sure when it appeared, I realized one or two months ago.

running the follow:
immagine

the configuration I use is the follow:

immagine

As a workaround I solved with an automation that is reloading the abb config entry, after few minutes from the switch off (that I detect with a ping sensor) to remove entities and few minutes after the switch on to restore entities.

alias: Abb Inverter reload
description: ""
trigger:

  • platform: state
    entity_id:
    • binary_sensor.ping_abb_inverter
      to: "off"
      for:
      hours: 0
      minutes: 3
      seconds: 0
  • platform: state
    entity_id:
    • binary_sensor.ping_abb_inverter
      to: "on"
      for:
      hours: 0
      minutes: 5
      seconds: 0
      condition: []
      action:
  • service: homeassistant.reload_config_entry
    data: {}
    target:
    device_id: b906894628baf2ad2040b276f38b68ca
    mode: single

No particular logs a part from the one when the integration is unable to reach the inverter. I reloaded HA so I lost the one from this night but I can gather tomorrow if you need.

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 6, 2023

In that phase starts a random delay in the HA responsivenessy. At least in my case it is easy to reproduce. It's enough to switch on/off a light, or a switch, repeatedly and after 4-5 cycles you can see that delay. That Light or switch does not respond for 2-3 secs and then start again switching fine, till the next 4-5 cycles.

Sorry, cannot reproduce here, I have no delays whatsoever on my lights/switches or anything else. Even if the automation tries to reach the inverter, it doesn't block other devices, this would be a severe limitation of HA, but since I know it's all async calls, that is not an issue.

BTW: when the inverter is off, you should see an error in the logs saying that the integration can't reach the inverter, and only one time, not every Xs (polling period). Can you check please?

@Claudio1L
Copy link
Author

Claudio1L commented Mar 6, 2023

The automation for the workaround already reloaded the config, so I need to check tomorrow night. I'm 99% sure it retry multiple times, not every 10 sec btw, but I let you know tomorrow. Thanks
If I find some time, tomorrow morning will try disabling wifi.

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 6, 2023

The only periodic connection is governed by the polling period. There's no other timer.

In any case, a tentative TCP connection to a host should not impact anything: if it does, you have other major issues with your setup. All these tasks are asynchronous and non blocking, that's the HA architecture that is based on python.

In any case, if you think that's what's happening to you, please provide the full HA log that shows the multiple recurring connections with the timeout error of the component. From the timestamps and the specific error we can try to understand what is going on.

Take also in consideration that the component is being used by many users, and you're the only one reporting this, and I'm not able to reproduce it here.

@Claudio1L
Copy link
Author

Claudio1L commented Mar 7, 2023

ok, confirmed the multiple retries.

I switched off the AP connecting the inverter, and tried to see. As you can see, there are 18 occurence in less than 3 minutes, about one every 10 seconds. Of course the issue started as soon as Inverter disconnected. I'm also available to show you via webex what's happening.

immagine

immagine

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 7, 2023

Does it stop after X retries or it goes on forever? It should go disabled after some retries. Also, like I told you, 10s is the polling period you configured. I would not advise polling so frequently, I have mine set to 30s.

In any case, the retry shouldn't impact anything on your HA, I'm not able to reproduce your slow response on other device. A TCP connection attempt every 10s shouldn't impact anything.

@Claudio1L
Copy link
Author

Tonight will see if stops after some retries and let you know. Anyway I agree with you, it shouldn't impact, but that's it. Eventually tomorrow I'll try to deploy the integration on a different instance exporting the entities on the main one, leveraging Remote Home-Assistant integration, to see if the problem will move to the new instance or if it's a local issue

@alexdelprete
Copy link
Owner

Even if I implemented some sort of auto-disable after X retries, you would need an automation in the morning to wake it up. I actually have an automation that wakes up the integration in the morning because if I restart HA during the evening, and the inverter is off, the integration goes in disabled state and in the morning it didn't autorestart. So in the morning at sunrise the automation triggers and wakes up the integration.

Do you use a Raspberry for HA?

@Claudio1L
Copy link
Author

yes, similar to what I did as workaround to the delay issue. An automation that simply reload the integration after the sunrise and after the sunset, when the ping sensor goes on or off. But only as workaround for the delay, because otherwise leaving the integration always active, automatically works when the inverter wake up. To be honest I don't know if the issue I'm having has been there from day 1, I can only say that around one month ago I noticed those random delays and has been difficult to understand the root cause, at least if was an integration issue or wifi issue or other things. Anyway I'm running a VM on esxi mini PC, and I'm spinning up a container on a different synology Nas to see if reproducible also there.

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 7, 2023

But only as workaround for the delay, because otherwise leaving the integration always active, automatically works when the inverter wake up

Unfortunately that's not the case. I created my automation because if during the evening, when inverter is off, I restarted HA, the integration would go disabled. So now at sunrise I wake it up through an automation.

To be honest I don't know if the issue I'm having has been there from day 1, I can only say that around one month ago I noticed those random delays and has been difficult to understand the root cause, at least if was an integration issue or wifi issue or other things

I started with HA on a Raspberry many years ago, and after 2 months I started having weird issues and lost lots of hours trying to understand the root cause, in the end, I found it: it was the RPi, too slow for a full HA with many devices. :)

I then started building my homelab with two mini servers and Proxmox, and everything was good from then, I don't use supervised, I use HA Core in a docker environment on a Proxmox LXC. Never had issues since then.

Anyway...trust me, a TCP connection can't cause a severe impact on other devices, unless you have a fundamental underlying problem.

Anyway I'm running a VM on esxi mini PC, and I'm spinning up a container on a different synology Nas to see if reproducible also there.

A NAS is not the ideal platform either, usually they don't have a good CPU, unless you have a high-end NAS obviously. Intel NUCs (even old ones, with an i3) are perfect for HA, if you want a dedicated device. If you want to virtualize, you need a decent server.

@Claudio1L
Copy link
Author

ok, let me do some more troubleshooting. I want to capture the traffic during the issue, to see if for some reasons there are broadcast storms generated somewere or something like that. VM cpu stay at 2-3% average and the memory is 30% busy, so it should not be a lack of resources. Let me see

@Claudio1L
Copy link
Author

Claudio1L commented Mar 7, 2023

tested with wireshark if something happen on the network after the switch off and seems fine. Captured a video to show you what I mean when there is the issue. You can see that sometime initially the light stop to answer. After reloading the integration, the entities disapper because the inverter is switched off and the issue does't happen anymore. I'm sending the video privately.

from the log I see first a single message EDIT: sorry, looking at the timestamp it is the last captured. Possibly the first after the config reload ?

immagine

Then a continous polling every 10 seconds, till the integration reload, then it stops.

immagine

immagine

@alexdelprete
Copy link
Owner

Claudio, can you please set a polling period of 30s and tell me tomorrow if it improved your light issue.

The only thing the integration does is trying to open a TCP connection and then it fails, then retries after the polling period. This cannot have an impact on a light switch, if it does, it means your system is not able to manage things properly. I could understand if the integration was trying to open 120 TCP connections per second, but we're talking about a TCP tentative connection every 10s. :)

I think there's an underlying issue and that you think the integration has a bug or something that causes your system to be "stressed" in some way, but that is not the case.

Setting the polling period to 30s will alleviate things a tiny bit...but I don't think the integration is the problem.

BTW: you should also monitor system resources closely...CPU/RAM/Network while it's working and while it's not and try to understand what is going on at system level. And also, did you take a look at the HA log to see if there are any warnings/errors, apart the integration timeout when inverter is off?

@Claudio1L
Copy link
Author

Claudio1L commented Mar 8, 2023

I'm spinning up another instance, disabling the main one, to see if can be reproduced on a fresh install. Another strange thing arise. Did the following: spinning up the new vm (with ip x.x.x.253/24) and installed the integration (no config yet). Disabled integration in the main instance (has ip x.x.x.254/24) and configured the integration on the new instance. Config went fine but no entities are detected. Tried some reboots, shut of the main instance but nothing. He started to detect entities on the new VM when I changed his ip address to .254, the one from the main instance (shutted down in the meantime). Is it possible that the inverter has a cached ip of the modbus client? Moving back to 253 lose entities again. No idea..

@Claudio1L
Copy link
Author

Claudio1L commented Mar 8, 2023

btw, polling 30s move the issue every 30 sec, so I understand what you are saying but there is a correlation for sure. Perhaps not related to the tcp session, instead related to some conflict with another process? don't know. In the past I had an issue for the modbus version conflicting with the solax integration. I'm going to reproduce it disabling solax to see if something change.

@Claudio1L
Copy link
Author

Claudio1L commented Mar 8, 2023

Failed also disabling solax. Last try is definitively the new VM. Later will try it changing the ip

@Claudio1L
Copy link
Author

after a quick test, it seems working fine on the new vm. Later will do some more tests and will try to troubleshoot what is conflicting on the main instance

@Claudio1L
Copy link
Author

Was thinking to the alternative to use a container I'm running for test on the nas just for the abb integration. It would be useful to understand if the modbus client IP is cached and possibly tomorrow morning, when the inverter power on, can use a different IP from the container.

@alexdelprete
Copy link
Owner

What do you mean by caching? Modbus TCP client is a library function, it does not have an IP. It uses the IP of the host where it's running.

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 8, 2023

Maybe I found a possible solution for you: disable "polling for updates". Go to integrations page, select the 3 dots, and select System Options.

image

Disable Polling for Updates:

image

This function is described here: https://www.home-assistant.io/blog/2021/06/02/release-20216/#disable-polling-updates-on-any-integration

What happens is that HA will disable the automatic polling. I disabled it last night, then my automation this morning waked up the integration as usual, and then the integration is doing the polling as usual, but HA won't do it on its own. Maybe it could work for you...let me know.

This is the automation to ensure the integration is running in the morning, no matter the state (due to HA restarts in the evening, etc.), the trigger is sunrise + 15 min. and the condition is that the sensor (you can pick any of the sensors of the integration) is unavailable.

alias: Keep ABB integration running
description: Check if ABB integration is unavailable
trigger:
  - platform: sun
    event: sunrise
    offset: "15"
condition:
  - condition: state
    entity_id: sensor.abb_vsn300_vendor_operating_state
    state: unavailable
    for:
      hours: 0
      minutes: 1
      seconds: 0
action:
  - service: homeassistant.reload_config_entry
    data: {}
    target:
      entity_id: sensor.abb_vsn300_vendor_operating_state
mode: single

@Claudio1L
Copy link
Author

What do you mean by caching? Modbus TCP client is a library function, it does not have an IP. It uses the IP of the host where it's running.

I mean, I'm seeing that if I move the integration on another HA istance, it works only if I move the IP address also, otherwise the integration installation works, but no entities are created. Looks like the HA IP address is cached in some way. It should not an Arp issue because I can ping from the new HA instance, so ARP/Mac table of the inverter are updated.
I haven't tried after the power on in the morning. It should accept a different HA ip I suppose, but haven't tried.

@Claudio1L
Copy link
Author

Maybe I found a possible solution for you: disable "polling for updates". Go to integrations page, select the 3 dots, and select System Options.

image

Disable Polling for Updates:

image

This function is described here: https://www.home-assistant.io/blog/2021/06/02/release-20216/#disable-polling-updates-on-any-integration

What happens is that HA will disable the automatic polling. I disabled it last night, then my automation this morning waked up the integration as usual, and then the integration is doing the polling as usual, but HA won't do it on its own. Maybe it could work for you...let me know.

This is the automation to ensure the integration is running in the morning, no matter the state (due to HA restarts in the evening, etc.), the trigger is sunrise + 15 min. and the condition is that the sensor (you can pick any of the sensors of the integration) is unavailable.

alias: Keep ABB integration running
description: Check if ABB integration is unavailable
trigger:
  - platform: sun
    event: sunrise
    offset: "15"
condition:
  - condition: state
    entity_id: sensor.abb_vsn300_vendor_operating_state
    state: unavailable
    for:
      hours: 0
      minutes: 1
      seconds: 0
action:
  - service: homeassistant.reload_config_entry
    data: {}
    target:
      entity_id: sensor.abb_vsn300_vendor_operating_state
mode: single

Just configured. Let you know soon. Thanks

@alexdelprete
Copy link
Owner

I'm seeing that if I move the integration on another HA istance, it works only if I move the IP address also, otherwise the integration installation works, but no entities are created. Looks like the HA IP address is cached in some way.

I don't know how you're migrating HA. But I changed server a couple of times since I use HA, with different IPs, and I never had any issue. If you install HA as a test instance on a new server, why shouldn't the integration work there? It's the same thing. I don't know how you're migrating things...maybe it's not che correct procedure. You are also on supervised, which adds a layer of complexity.

@Claudio1L
Copy link
Author

I can't show you because the inverter switched off, Tomorrow I'll send you the screenshot of what I mean. Basically I do what I did in the main installation. Install the integration in a fresh ha istance, with a different IP from the main one of course, the integration installs fine but entities are not discovered. If I move the ip address from the main instance to the new one entities immediately appear. It's like if the inverter expect to talk with the ip from the main instance only :-) . Anyway I want to try with a different IP when the inverter switch on in the morning. Perhaps is a sort of caching of the first host contacting the inverter?. I don't know. All the strange things to me :-)

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 8, 2023

If I move the ip address from the main instance to the new one entities immediately appear. It's like if the inverter expect to talk with the ip from the main instance only :-) . Anyway I want to try with a different IP when the inverter switch on in the morning. Perhaps is a sort of caching of the first host contacting the inverter?

I have 2 HA installations on 2 different servers, the second is a test bed, and the integration runs perfectly on both of them. The inverter does not have any caching functionality. It uses a modbus tcp server that answers to queries, and it doesn't care of the source ip, it's pretty stupid as a protocol. Do you have the VSN300/VSN700 addon card? Or did you find it integrated in the inverter?

I don't know. All the strange things to me :-)

Yes, I can confirm what you're saying. :)

@Claudio1L
Copy link
Author

Claudio1L commented Mar 8, 2023

Maybe I found a possible solution for you: disable "polling for updates". Go to integrations page, select the 3 dots, and select System Options.

Just configured. Let you know soon. Thanks

no way, also disabling "polling for update" same issue.

and the retries increase
immagine

@Claudio1L
Copy link
Author

Do you have the VSN300/VSN700 addon card? Or did you find it integrated in the inverter?

Integrated

@Claudio1L
Copy link
Author

Claudio1L commented Mar 8, 2023

anyway, tomorrow morning want to try a first contact with the new instance with different IP to see what happen

@alexdelprete
Copy link
Owner

whant to try a first contact with the new instance with different IP to see what happen

on the new instance, if you have the problem that it doesn't create the entities, you should check HA log for errors.

@Claudio1L
Copy link
Author

whant to try a first contact with the new instance with different IP to see what happen

on the new instance, if you have the problem that it doesn't create the entities, you should check HA log for errors.

I'll do it

@alexdelprete
Copy link
Owner

alexdelprete commented Mar 8, 2023

Also, make sure the other instance of the ABB integration is not running. Otherwise you'll add another "strange issue". :)

@Claudio1L
Copy link
Author

Also, make sure the other instance of the ABB integration is not running. Otherwise you'll add another "strange issue". :)

yes. I disable it now so avoid to forget tomorrow

@Claudio1L
Copy link
Author

Claudio1L commented Mar 9, 2023

quick update.

today I spun up a container on the Nas with only the abb integration and a single shelly switch to test the delay issue.

  1. the integration installed fine, using a different ip (the NAS one) and it exposed the entities correctly
  2. I tested the delay issue disconnecting the inverter and seems not reproducible here
  3. Powering down the container and reenabling the app integration in the main instance working fine (no ip add issue)
  4. Tested both integrations active at the same time and was working fine for both. Of course in the main instance the delay problem still there

Better than yersterday, with some reasonable results.

At this point, will leave the integration running on the container instance, exposing the entities to the main one with the remote HA integration. Is working fine because I can expose the entities with exactly the same name, not losing statistics
Another positive side effect is that rebooting the main instance during the night do not disable the entities imported from the container.
Not the best solution, but at the moment is working. As soon as will have some time, will try to rebuild a fresh vm with all the integrations, testing step by step if there are conflicts.

@Claudio1L
Copy link
Author

Thanks a lot for the patient, of course :-)

@alexdelprete
Copy link
Owner

No problem, glad to have confirmation that it's not the integration.

@bryanyork
Copy link

@alexdelprete I'm getting this too, only at night after the inverters shut off. It's causing my Home Assistant install to be very slow and have random delays. If I disable the integration the delays go away. Also, during the day while the inverters are online, there are no delays. I have added an automation to disable the integration after sunset and enable after sunrise for now.

Additionally there appears to be a bug when the inverter comes online where it might return 0, causing the Home Assistant Energy component to register 65k kWh because it's doing a diff of readings. I believe I submitted a PR for this fix before the refactor. I can take a look again to see where this might be happening. We should not return a value if the inverter is not online properly.

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 12, 2023

  1. An integration that polls a device shouldn't slow down your system. If it does, it means you have other critical underlying issues. Anyway, to disable correctly the integration when the inverter is off, you can read here: https://community.home-assistant.io/t/custom-component-abb-power-one-fimer-pv-inverters-sunspec-modbus-tcp/316363/254?u=alexdelprete

  2. Unfortunately, despite following the documentation regarding handling of unavailable device (https://developers.home-assistant.io/docs/integration_setup_failures#handling-offline-or-unavailable-devices-and-services), if you check my code in __init__.py it does what's advised, it seems that the automatic handling of ConfigEntryNotReady is not working as expected. When your inverter is off, what is the exact logged error message that was repeated? If you're a dev and want to help fixing this, I'd be more than happy, since I'm not a dev.

    try:
        hub = ABBPowerOnePVISunSpecHub(hass, name, host, port, slave_id, base_addr)
        coordinator = HubDataUpdateCoordinator(hass, hub=hub, entry=entry)
        await coordinator.async_config_entry_first_refresh()
    except ConnectionException as connerr:
        raise ConfigEntryNotReady(f"Problem connecting to device {host}:{port} - Exception: {connerr}") from connerr
  1. What sensor are you using to feed the energy integration? There's no bug when the inverter is back online, because the production counter is a total_increasing one, you should use that for the Energy integration. Furthermore, for the total production there's a check in the code that ignores the value if it's 0 or less than the previous value.

  2. Your PR Fix modbus return logic to handle temporary failures #25 was considered and I modified the code accordingly.

@bryanyork
Copy link

I had wrote a wrapper to save the state into a template sensor, perhaps that is adding some issues. I've just removed it and am using the entity directly now. Let me see in a few days if the issue goes away. For now my automation on sunrise/sunset seems to alleviate things.

Thanks for ensuring the old PR was considered! Will keep you posted.

@Claudio1L
Copy link
Author

Claudio1L commented Sep 14, 2023 via email

@alexdelprete
Copy link
Owner

I moved the ABB integration to a separated container HA instance, running only that integration, and then exposing the entities to the main HA instance leveraging https://github.com/custom-components/remote_homeassistant In this way, the main HA is not slowed down and counters are not corrupted by the integration disabling

So, an integration in an instance slows it down and in another instance it doesn't? Weird. I would point to other issues in the instance that slows down.

Counters corrupted? Never seen anything like that. You can disable an integration at runtime and that won't cause any corruption, it's a message/transaction based architecture.

Anyway...last beta version is under test, and it solves the retry/log spamming issue finally. I will monitor it for a couple of days then release a new stable version so there will be no need to disable the integration when the inverter is off.

BTW: the solution was to let HA manage the retry automatically in background, so it does the exact same thing, but it doesn't write errors to the log except for the first couple of retries.

@alexdelprete
Copy link
Owner

@bryanyork @Claudio1L please test beta.5. I'm testing it since 24h without disabling the integration or any external automation. The solution was to test if Modbus TCP was available at socket level before trying the modbus connect (pymodbus call). This avoids all the errors etc. The retry mechanism is managed by HA itself, and the integration will be online automatically as soon as the TCP port is available (inverter on) and the Modbus TCP connect is processed correctly.

@bryanyork
Copy link

image
Still seeing spikes in the morning when the inverter comes online. Let me try the latest beta now and see if that is fixed.

@Claudio1L
Copy link
Author

Claudio1L commented Sep 17, 2023 via email

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 17, 2023

Still seeing spikes in the morning when the inverter comes online. Let me try the latest beta now and see if that is fixed.

Latest beta doesn't address a spike, because there's no spike. You can read release notes to understand what's changed.

I can't understand this spike thing: the production is read from the inverter's modbus registry, no data manipulation whatsoever.

Showing a graph doesn't tell me anything that could help me diagnose your issue: enable debug in the integration, and pass me the full log so we can check what value has been actually read from the inverter's modbus registry.

image

When enabled, open the full HA log, filter with keyword "abb" and you'll find these:

2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup config entry for ABB
2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Data: {'port': 502, 'slave_id': 2, 'base_addr': 0, 'scan_interval': 60, 'name': 'ABB VSN300', 'host': 'abb-vsn300.axel.dom'}
2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Options: {}
2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup entry with scan interval 0:01:00. Host: abb-vsn300.axel.dom Port: 502 ID: 2
2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-17 09:05:19.685 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP abb-vsn300.axel.dom port 502 slave id 2
2023-09-17 09:05:20.857 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-17 09:05:20.868 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-17 09:05:20.868 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-17 09:05:20.882 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-17 09:05:20.882 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-17 09:05:20.882 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-17 09:05:20.882 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G82-
2023-09-17 09:05:20.882 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: X
2023-09-17 09:05:20.883 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: X - opt_model_int: 88
2023-09-17 09:05:20.883 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-10.0-OUTD
2023-09-17 09:05:20.883 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C008
2023-09-17 09:05:20.883 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 077909-3G82-3112
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 103
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Three Phase
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 92089232
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 1
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 2293
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 24.3
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-17 09:05:20.888 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-17 09:05:20.889 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-17 09:05:20.894 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 1.209 seconds (success: True)

You'll see (read_rt_101_103) Total Energy Value Read: 92089232 and that is the energy value of Total Production. It's a total increasing value coming from the inverter.

I'm pretty confident your spikes are due to data manipulation on your end, a template/calculated/integral sensor, or conversion, or something like that. I never seen another report regarding bad data regarding the integration.

Furthermore, I don't understand the graph you posted: why do you have 2 sensors for total energy?

Please open the total_energy sensor from the integration directly, and shome me the HA default graph. So we can see what has HA actually read from the sensor that is managed by the integration.

image

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 17, 2023

There are some differences, between the two instances. The main instance is based on HAOS, it’s a ESXi VM and include all the home automations. The second instance is a simple container running only the ABB integration and another integration for a remote thermostat (another home), not supporting multitenancy so I had to run in a different instance to have two of them. Don’t know if the slow effect appears also in the second instance because I don’t have light or switch to switch on/off, so also if should be there does not affect the main instance.

HA checks for device availability by polling, each 60s, that is delegated to HA internal functions, and should not slowdown anything.

In latest 3.1 I also introduced a check if the TCP port is actually open, before doing any I/O operation, to speed up even more things.

A poll of a device should not slowdown a system. It would if you do it evert millisecond, not every 60s.

If you want to understand how it works better, you can read here: https://developers.home-assistant.io/docs/integration_setup_failures

@bryanyork
Copy link

I will enable debug logging and see if I can catch this again. I have two VSN300's for my two inverters, hence left and right. This was with version v3.0.0 and I had already disabled any template sensors. I just disabled my automation to disable/ enable at sunrise. Will keep posted with some better debugging.

@bryanyork
Copy link

The integration dropped to 0, hence my suspicion. Let me look at the code.
Screenshot_20230917_132922_Home Assistant

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 17, 2023

The integration dropped to 0, hence my suspicion. Let me look at the code. !

Impossible:

On start, totalenergy is initialized to 1, then code checks if value received is less than previous. If it is, it's skipped and debug message is written to the log.

        # registers 94 to 96
        totalenergy = decoder.decode_32bit_uint()
        totalenergysf = decoder.decode_16bit_uint()
        totalenergy = self.calculate_value(totalenergy, totalenergysf)
        # ensure that totalenergy is always an increasing value (total_increasing)
        _LOGGER.debug("(read_rt_101_103) Total Energy Value Read: %s", totalenergy)
        _LOGGER.debug("(read_rt_101_103) Total Energy Previous Value: %s", self.data["totalenergy"])
        if totalenergy < self.data["totalenergy"]:
            _LOGGER.error("(read_rt_101_103) Total Energy less than previous value! Value Read: %s - Previous Value: %s", totalenergy, self.data["totalenergy"])
        else:
            self.data["totalenergy"] = totalenergy

I need to see the debug log, or the graph of the integration sensor, "Right Inverter Total Energy" might be a calculated sensor. The integration sensor total_energy can't be 0.

@Claudio1L
Copy link
Author

Claudio1L commented Sep 19, 2023 via email

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 19, 2023

Nothing to do. Also after upgrade to latest 3.1.2, every 60 secs the slowdown happen, also if the log report only the first event.

So, on one of your two HA installations, when the integration does a modbus tcp connection and retrieves the data from the inverter (the operation lasts 0.05-0.10s, it would be interesting seeing your debug log to check how long does the fetch operation last), you see your HA installation slowing down. On the second installation it doesn't slow down? Very strange, because you're the only one with this issue. @bryanyork had the problem only when the inverter went offline, and that has been solved now in the code. What hardware are you using to host HA?

On top, after the shutdown, all entities goes to unavailable state and this corrupt some templates I built on top of those entities.

When a device goes offline, the sensors must go to state UNAVAILABLE (it's a known state). That's how it's supposed to work and it is documented in HA docs. Why does a sensor have to be available when the device from which the sensor gets its data is offline? Think about it. :)

Your problem is how you created the templates on top of the sensors, you should test in your code if the sensor is unavailable and then decide what to do with the template sensor.

In the previous 2.6.6 they stay at the last value till the inverter restart in the morning.

That was because the integration kept polling (throwing error in the log) until the device next morning came up. It was a bad behaviour, that has now been fixed, and the integration respects HA's best practices.

If you created template sensors, simply use them, and test when the real sensors go unavailable, and use last state of template ones.

@Claudio1L
Copy link
Author

Claudio1L commented Sep 19, 2023 via email

@bryanyork
Copy link

Running the latest version it seems like the 0 readings have gone away. I see this in my logs however:
Logger: custom_components.abb_powerone_pvi_sunspec
Source: custom_components/abb_powerone_pvi_sunspec/api.py:175
Integration: ABB/Power-One/FIMER PVI SunSpec (documentation, issues)
First occurred: September 23, 2023 at 7:04:28 AM (3 occurrences)
Last logged: 7:14:10 AM

(read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39278912
(read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39295688

However, at night when the inverters are offline I still see some pauses to my HA install. I will try and debug how this might be caused. Again, if I disable the integration, the pauses go away.

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 25, 2023

(read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39278912
(read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39295688

Those are zero readings Bryan. See "Value Read"? Should never be zero for Total Energy, and that is read from the Inverter.

You can enable debug log so we can have some more information in HA log when it happens. This is a debug log of one read-cycle:

2023-09-25 12:39:50.974 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-25 12:39:50.974 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP abb-vsn300.axel.dom port 502 slave id 2
2023-09-25 12:39:51.127 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-25 12:39:51.131 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-25 12:39:51.131 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G82-
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: X
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: X - opt_model_int: 88
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-10.0-OUTD
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C008
2023-09-25 12:39:51.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 077909-3G82-3112
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 103
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Three Phase
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 92271200
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 92271128
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 3987
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 25.2
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-25 12:39:51.154 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-25 12:39:51.161 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.187 seconds (success: True)

@bryanyork
Copy link

bryanyork commented Sep 26, 2023

@alexdelprete here are the debug logs. Is it possible that Status Vendor Value read: Sending Parameters means the inverter is not yet ready?

2023-09-24 07:04:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:04:33.275 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:04:33.382 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:04:33.382 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:04:33.426 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:04:33.427 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:04:33.433 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:04:33.433 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Self Test
2023-09-24 07:04:33.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:04:33.444 INFO (MainThread) [custom_components.abb_powerone_pvi_sunspec] Fetching abb_powerone_pvi_sunspec data recovered
2023-09-24 07:04:33.444 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.263 seconds (success: True)
2023-09-24 07:04:46.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:04:46.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:04:49.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:04:49.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:04:49.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:05:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:05:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:05:33.487 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:05:33.492 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:05:33.492 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:05:33.505 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Self Test
2023-09-24 07:05:33.510 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:05:33.515 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.334 seconds (success: True)
2023-09-24 07:05:49.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:05:49.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:05:52.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:05:52.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:05:52.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:06:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:06:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:06:33.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:06:33.402 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:06:33.402 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:06:33.422 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sensor Test + Measure Riso
2023-09-24 07:06:33.435 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:06:33.439 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.258 seconds (success: True)
2023-09-24 07:06:52.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:06:52.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:06:55.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:06:55.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:06:55.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.004 seconds (success: False)
2023-09-24 07:07:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:07:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:07:33.294 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:07:33.400 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:07:33.400 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:07:33.408 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sensor Test + Measure Riso
2023-09-24 07:07:33.413 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:07:33.418 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.237 seconds (success: True)
2023-09-24 07:07:55.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:07:55.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:07:58.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:07:58.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:07:58.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:08:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:08:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:08:33.198 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:08:33.211 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:08:33.211 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:08:33.221 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:08:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:08:33.227 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:08:33.227 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:08:33.227 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sensor Test + Measure Riso
2023-09-24 07:08:33.227 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:08:33.232 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.052 seconds (success: True)
2023-09-24 07:08:58.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:08:58.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:09:01.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:09:01.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:09:01.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:09:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:09:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:09:33.409 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:09:33.521 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:09:33.521 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:09:33.532 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:09:33.542 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:09:33.542 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:09:33.542 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:09:33.542 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Checking Grid
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sensor Test + Measure Riso
2023-09-24 07:09:33.543 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:09:33.548 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.367 seconds (success: True)
2023-09-24 07:10:01.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:10:01.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:10:04.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:10:04.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:10:04.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:10:33.182 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:10:33.182 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:10:33.222 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:10:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:10:33.226 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:10:33.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:10:33.247 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:10:33.251 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:10:33.251 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:10:33.251 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:10:33.251 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:10:33.252 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:10:33.252 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.069 seconds (success: True)
2023-09-24 07:11:04.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:11:04.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:11:07.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:11:07.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:11:07.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:11:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:11:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:11:33.328 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:11:33.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:11:33.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:11:33.341 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:11:33.346 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:11:33.346 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:11:33.346 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:11:33.346 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:11:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:11:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.176 seconds (success: True)
2023-09-24 07:12:07.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:12:07.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:12:10.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-24 07:12:10.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active 192.168.25.51:502
2023-09-24 07:12:10.282 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 3.003 seconds (success: False)
2023-09-24 07:12:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:12:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:12:33.329 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:12:33.344 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:12:33.344 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:12:33.351 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:12:33.357 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:12:33.362 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.180 seconds (success: True)
2023-09-24 07:13:10.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:13:10.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:13:10.295 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:13:10.405 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:13:10.406 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 3 - Base Address: 0)
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 3
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:13:10.456 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202826-3G96-3615
2023-09-24 07:13:10.465 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 3
2023-09-24 07:13:10.465 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:13:10.465 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:13:10.465 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 0
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 39295688
2023-09-24 07:13:10.466 ERROR (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39295688
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Run
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sending Parameters
2023-09-24 07:13:10.466 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:13:10.471 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 3
2023-09-24 07:13:10.471 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:13:10.472 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:13:10.472 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:13:10.472 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:13:10.472 INFO (MainThread) [custom_components.abb_powerone_pvi_sunspec] Fetching abb_powerone_pvi_sunspec data recovered
2023-09-24 07:13:10.472 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.194 seconds (success: True)
2023-09-24 07:13:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:13:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:13:33.339 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:13:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:13:33.347 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:13:33.372 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:13:33.377 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:13:33.383 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.202 seconds (success: True)
2023-09-24 07:14:10.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:14:10.278 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:14:10.311 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:14:10.417 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:14:10.417 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 3 - Base Address: 0)
2023-09-24 07:14:10.433 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 3
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:14:10.434 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202826-3G96-3615
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 3
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 0
2023-09-24 07:14:10.447 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 39295688
2023-09-24 07:14:10.447 ERROR (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 39295688
2023-09-24 07:14:10.448 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:14:10.448 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:14:10.448 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Run
2023-09-24 07:14:10.448 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sending Parameters
2023-09-24 07:14:10.448 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 3
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:14:10.458 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.180 seconds (success: True)
2023-09-24 07:14:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:14:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:14:33.240 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:14:33.345 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:14:33.345 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:14:33.355 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:14:33.361 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:14:33.366 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.185 seconds (success: True)
2023-09-24 07:15:10.280 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:15:10.280 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:15:10.310 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:15:10.320 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:15:10.321 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 3 - Base Address: 0)
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 3
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:15:10.332 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202826-3G96-3615
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 3
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 39295688
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 39295688
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:15:10.338 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 3
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:15:10.343 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.064 seconds (success: True)
2023-09-24 07:15:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:15:33.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:15:33.963 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:15:34.068 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:15:34.068 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:15:34.080 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:15:34.080 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:15:34.080 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:15:34.080 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:15:34.081 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:15:34.081 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:15:34.081 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:15:34.081 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:15:34.081 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:15:34.086 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:15:34.092 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.911 seconds (success: True)
2023-09-24 07:16:10.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:16:10.279 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.51 port 502 slave id 3
2023-09-24 07:16:10.528 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:16:10.636 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:16:10.637 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 3 - Base Address: 0)
2023-09-24 07:16:10.651 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 3
2023-09-24 07:16:10.651 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:16:10.651 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:16:10.652 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202826-3G96-3615
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 3
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 39295688
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 39295688
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:16:10.660 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 3
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-24 07:16:10.667 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.389 seconds (success: True)
2023-09-24 07:16:34.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-24 07:16:34.181 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP 192.168.25.50 port 502 slave id 2
2023-09-24 07:16:34.281 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-24 07:16:34.386 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-24 07:16:34.386 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-24 07:16:34.393 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44035664
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44035664
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-24 07:16:34.399 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-24 07:16:34.405 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-24 07:16:34.405 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-24 07:16:34.405 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-24 07:16:34.405 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-24 07:16:34.406 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 26, 2023

here are the debug logs. Is it possible that Status Vendor Value read: Sending Parameters means the inverter is not yet ready?

No, because the inverter answered correctly. I never saw this "Sending Parameters" status. Might mean that it is sending data to AuroraVision on cloud maybe? I never saw this in my logs...maybe it depends on the logger fw version...I have v1.9.1 on VSN300.

Anyway, that 0 value is ignored by code, so it's not an issue. If you have 0 in your HA graphs, it's not due to the component's sensors.

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 26, 2023

@Claudio1L @bryanyork I released v3.1.3, introduced thread locking on connect/disconnect/read operations, I don't know if it might benefit your specific issues, but it's worth trying.

@Claudio1L
Copy link
Author

Claudio1L commented Sep 29, 2023 via email

@alexdelprete
Copy link
Owner

alexdelprete commented Sep 29, 2023

Not had the opportunity to test it. I’ll let you know. Not sure you have seen this: CJNE/ha-sunspec#165 (comment)

My code is a bit different and is based on different libraries (I use pymodbus, the other integration uses pysunspec2), and in recent versions, to avoid pymodbus errors that I couldn't disable when inverter was off, I introduced a socket check to see if the modbus port on the VSN card is open or not. So I don't do any connect now, unless the port is available. There's no blocking issue that could slow HA.

Furthermore, have you seen any serious error like this in the logs of my component? If so, please show me:

Logger: homeassistant.util.async_
Source: util/async_.py:164
First occurred: 21:44:31 (3 occurrences)
Last logged: 21:51:15

Detected blocking call to sleep inside the event loop. This is causing stability issues. Please report issue to the custom integration author for sunspec doing blocking calls at custom_components/sunspec/api.py, line 159: client.scan(

@bryanyork
Copy link

@alexdelprete I got another spike, it seems that if I upgrade home assistant overnight perhaps it put the integration in some weird state, it might cause this. According to debug logs the previous reading was 1 not 0, which is strange. Because I upgraded I missed the log before I upgraded. I will work on sending logs to a central Loki server to see if I can catch this next time.

2023-09-28 06:56:43.089 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup config entry for ABB
2023-09-28 06:56:43.089 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Data: {'name': 'Left Inverter', 'host': '192.168.25.50', 'port': 502, 'slave_id': 2, 'base_addr': 0, 'scan_interval': 60}
2023-09-28 06:56:43.089 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Options: {}
2023-09-28 06:56:43.089 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup entry with scan interval 0:01:00. Host: 192.168.25.50 Port: 502 ID: 2
2023-09-28 06:56:43.090 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-28 06:56:43.090 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP: 192.168.25.50 port: 502 slave id: 2 timeout: 59
2023-09-28 06:56:43.144 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Check_Port (SUCCESS): opened socket on ip:port 192.168.25.50:502 within a 5 seconds timeout.
2023-09-28 06:56:43.144 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-28 06:56:43.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-28 06:56:43.149 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-28 06:56:43.193 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 0
2023-09-28 06:56:43.200 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 1
2023-09-28 06:56:43.200 ERROR (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy less than previous value! Value Read: 0 - Previous Value: 1
2023-09-28 06:56:43.201 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-28 06:56:43.201 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-28 06:56:43.201 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Run
2023-09-28 06:56:43.201 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Sending Parameters
2023-09-28 06:56:43.201 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-28 06:56:43.206 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-28 06:56:43.207 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-28 06:56:43.207 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-28 06:56:43.207 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-28 06:56:43.207 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-28 06:56:43.207 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.117 seconds (success: True)
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) Name: Left Inverter
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) Manufacturer: Power-One
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) Model: PVI-3.0-OUTD
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) SW Version: C041
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) Inverter Type (str): Single Phase
2023-09-28 06:56:43.208 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (sensor) Serial#: 202853-3G96-3615
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup config entry for ABB
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Data: {'name': 'Right Inverter', 'host': '192.168.25.51', 'port': 502, 'slave_id': 3, 'base_addr': 0, 'scan_interval': 60}
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Options: {}
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup entry with scan interval 0:01:00. Host: 192.168.25.51 Port: 502 ID: 3
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-28 06:56:53.935 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP: 192.168.25.51 port: 502 slave id: 3 timeout: 59
2023-09-28 06:56:58.940 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Check_Port (ERROR): couldn't open a socket on ip:port 192.168.25.51:502 for 5 seconds.
2023-09-28 06:56:58.940 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-28 06:56:58.940 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active on 192.168.25.51:502
2023-09-28 06:56:58.940 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 5.005 seconds (success: False)
2023-09-28 06:57:43.266 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-28 06:57:43.267 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP: 192.168.25.50 port: 502 slave id: 2 timeout: 59
2023-09-28 06:57:43.271 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Check_Port (SUCCESS): opened socket on ip:port 192.168.25.50:502 within a 5 seconds timeout.
2023-09-28 06:57:43.271 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-28 06:57:43.273 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-28 06:57:43.273 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-28 06:57:43.292 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44102524
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 1
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-28 06:57:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-28 06:57:43.302 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.036 seconds (success: True)
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup config entry for ABB
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Data: {'name': 'Right Inverter', 'host': '192.168.25.51', 'port': 502, 'slave_id': 3, 'base_addr': 0, 'scan_interval': 60}
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Options: {}
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Setup entry with scan interval 0:01:00. Host: 192.168.25.51 Port: 502 ID: 3
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-28 06:58:19.242 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP: 192.168.25.51 port: 502 slave id: 3 timeout: 59
2023-09-28 06:58:24.248 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Check_Port (ERROR): couldn't open a socket on ip:port 192.168.25.51:502 for 5 seconds.
2023-09-28 06:58:24.248 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter not ready for Modbus TCP connection
2023-09-28 06:58:24.248 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Async Update Data error: Inverter not active on 192.168.25.51:502
2023-09-28 06:58:24.248 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 5.006 seconds (success: False)
2023-09-28 06:58:43.267 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] ABB SunSpec Update data coordinator update
2023-09-28 06:58:43.267 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Hub connect to IP: 192.168.25.50 port: 502 slave id: 2 timeout: 59
2023-09-28 06:58:43.272 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Check_Port (SUCCESS): opened socket on ip:port 192.168.25.50:502 within a 5 seconds timeout.
2023-09-28 06:58:43.272 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Inverter ready for Modbus TCP connection
2023-09-28 06:58:43.276 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Modbus TCP Client connected
2023-09-28 06:58:43.276 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Start Get data (Slave ID: 2 - Base Address: 0)
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Slave ID: 2
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Base Address: 0
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Manufacturer: Power-One
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Model: -3G96-
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Options: 1
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_printable) opt_model: 1 - opt_model_int: 49
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (opt_comm_model) comm_model: PVI-3.0-OUTD
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Version: C041
2023-09-28 06:58:43.287 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_1) Sernum: 202853-3G96-3615
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Slave ID: 2
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Base Address: 0
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (int): 101
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Inverter Type (str): Single Phase
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Value Read: 44102524
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Total Energy Previous Value: 44102524
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) DC Power Value read: 0
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Temp Cab Value read: 0.0
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Device Status Value read: Out OC
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Status Vendor Value read: Run
2023-09-28 06:58:43.297 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_101_103) Completed
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Slave ID: 2
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Base Address: 0
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] (read_rt_160) Completed
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Closing Modbus TCP connection
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] End Get data
2023-09-28 06:58:43.306 DEBUG (MainThread) [custom_components.abb_powerone_pvi_sunspec] Finished fetching abb_powerone_pvi_sunspec data in 0.040 seconds (success: True)

@alexdelprete
Copy link
Owner

Previous reading is 1 because at init (before any read) the variable is initialized with 1.

The only weird thing is why your inverter reads zero. Anyway, zero is skipped, so it shouldn't cause any issue.

And I don't understand what you mean with overnight upgrades of HA. Reading zero from a modbus register is not related to HA upgrade.

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

No branches or pull requests

3 participants