Rückmeldungen Beta 1.9.273

[JPRider]

Wofür mir jetzt aber jegliche Erklärung fehlt ist die Situation kurz vor Ladeschluss (siehe Graph im Bild).

Ab dem Zeitpunkt wo die Ladeleistung kurz vor erreichen der 100% Vollladung zurückfahrt, passen plötzlich alle Werte, obwohl noch mit rund 2,9 kW geladen wird.

Und wenn der Speicher voll ist, hat es ja bisher auch schon gepasst. Im Übergang von 99% auf 100% Speicherladung, also wie im Auszug unten zu sehen von rund 2,9 kW Speicherladung auf 0 W Speicherladung ist keine Veränderung mehr am PV Ertrag und dem Hausverbrauch zu verzeichnen.

[LenaK]

@JPRider, Dr.Mad Ich habe die Logmeldungen erweitert, dass alle WR-Werte einzeln geloggt werden. So können wir feststellen, ob Dr. Mads These zutrifft.

Ich könnte mir vorstellen, dass der Hybridwechselrichter keine negativen PV-Einspeisewerte berichtet sondern nur positive/mindestens null. Addiert man nun die Speicherladeleistung zu den von allen Wechselrichtern berichteten PV-Leistungen, zählt man alle PV-Leistungen doppelt ausgenommen die des Hybridwechselrichters; einzig dieser berichtet ja null als AC-seitige PV-Einspeisung. Macht das Sinn?

Nachtrag: Hat der Hybridwechselrichter vielleicht einen AC-Bezugszähler (= Speicherladung - PV-Erzeugung), quasi das negative Gegenstück zum AC-Einspeisezähler (= PV-Erzeugung + Speicherentladung), der von OpenWB bisher nicht ausgelesen/mit einbezogen wird?

[edits: Typos, Nachtrag]

[JPRider]

@JPRider, Dr.Mad Ich habe die Logmeldungen erweitert, dass alle WR-Werte einzeln geloggt werden. So können wir feststellen, ob Dr. Mads These zutrifft.

Logmeldungen erweitert soll heißen, Nightly 1.9.274 von heute nochmal als Update einspielen und dann Werte aus dem Debuglog im Mode 2 einstellen?

Die These von @Dr.Mad wäre ein Ansatz, erklärt aber nicht das Verhalten kurz vorm Ende der Ladephase wo es dann trotz Speicherladung auf einmal passt (wie auf den Graphen im Bild weiter oben).

[LenaK]

Logmeldungen erweitert soll heißen, Nightly 1.9.274 von heute nochmal als Update einspielen und dann Werte aus dem Debuglog im Mode 2 einstellen?

Ja bitte.

Die These von @Dr.Mad wäre ein Ansatz, erklärt aber nicht das Verhalten kurz vorm Ende der Ladephase wo es dann trotz Speicherladung auf einmal passt (wie auf den Graphen im Bild weiter oben).

Du hast in dem Post weiter oben auch noch geschrieben, dass kurz vor Erreichen der 100% die Ladeleistung zurück gefahren wurde. Evtl wird dann der Speicher nicht mehr mit Leistung aus beiden WR geladen, was zu den falschen PV-Werten geführt hat.

[JPRider]

Du hast in dem Post weiter oben auch noch geschrieben, dass kurz vor Erreichen der 100% die Ladeleistung zurück gefahren wurde. Evtl wird dann der Speicher nicht mehr mit Leistung aus beiden WR geladen, was zu den falschen PV-Werten geführt hat.

Wäre möglich.

[JPRider]

OK, Logdaten von der Ladephase stelle ich heute Abend ein. Ich versuche den Zeitpunkt bzw. Übergang "kurz vor Voll" nochmal zu erwischen.

Ergänzend zu der im Tageslog angezeigten PV Erzeugung in der Nacht ist mir heute morgen auf der OpenWB Statusseite noch aufgefallen, dass richtigerweise ohne Einstrahlung keine PV Leistung angezeigt wird (also PV 0 W) aber der Wert in Klammern für den Tagesertrag bereits auf über 3 kW stand. Das wird dann vermutlich der Teil der Speicherentladung sein der im Tageslog als "nächtlicher" PV Ertrag angezeigt wird.

[Dr. Mad]

@LenaK Hier ein Ausschnitt aus dem Debug Log in Mode 2 mit einer Iteration der Regelschleife (ein längeres Debug Log ist angehängt) Soweit ich das erkennen kann, berichtet der Hybridwechselrichter (192.168.178.124) tatsächlich Leistung null. Nach den Webseiten der Wechselrichter waren die Werte zu dem Zeitpunkt rund: Batterieladung 2800 W, PV-Erzeugung Hybrid-WR 1850 W, Sunny Tripower 200 W, Sunny Boy 650 W, Solaredge 150 W. Auch zum Vergleich noch zwei Screenshots von OpenWB und SMA Sunnyportal.

Code: Alles auswählen

2022-08-25 08:15:09: PID: 18816: **** WARNING **** Regulation loop needs 8 seconds (LV0) at 57 cleanup /var/www/html/openWB/regel.sh
set-currents: setze ladung auf 6A
2022-08-25 08:15:08: PID: 18816: uberschuss 2580 wattbezug 5 ladestatus 1 llsoll 6 pvwatt -3721 mindestuberschussphasen 500 wattkombiniert 2580 schaltschwelle 230 (LV2) at 576 main /var/www/html/openWB/regel.sh
2022-08-25 08:15:08: PID: 18816: anzahlphasen 1 (LV1) at 575 main /var/www/html/openWB/regel.sh
2022-08-25 08:15:08: PID: 18816: LP1 Modbus 6 korrekt (LV1) at 236 evsemodbuscheck evsedintest.sh
2022-08-25 08:15:06: PID: 18816: Timing Umschaltung: 480 / 480 (LV1) at 17 u1p3pswitch u1p3p.sh
2022-08-25 08:15:06: PID: 18816: automatische Umschaltung aktiv (LV1) at 16 u1p3pswitch u1p3p.sh
2022-08-25 08:15:06: PID: 18816: Zeit zum abfragen aller Werte 5 Sekunden (LV1) at 176 main /var/www/html/openWB/regel.sh
2022-08-25 08:15:05: PID: 18816: chargestatlp1 0 chargestatlp2 0 chargestatlp3 0 (LV1) at 1303 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: plugstatlp1 0 plugstatlp2 0 plugstatlp3 0 (LV1) at 1302 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: lp1enabled 1 lp2enabled 1 lp3enabled 1 (LV1) at 1301 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: EVU 1:V/-2A 2: V/1A 3: V/2A (LV1) at 1300 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: lla3 0 llv3 235.3 llas13 0 llas23  soclp1 0 soclp2 0 (LV1) at 1299 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: lla2 0 llv2 234.7 llas12 0 llas22  sofortll 16 hausverbrauch 1141 wattbezug 5 uberschuss 2580 (LV1) at 1298 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: lla1 0 llv1 235.5 llas11 0 llas21  mindestuberschuss 500 abschaltuberschuss -5 lademodus 2 (LV1) at 1297 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: pv1watt -3561 pv2watt -160 pvwatt -3721 ladeleistung 0 llalt 6 nachtladen 0 nachtladen 0 minimalA 6 maximalA 32 (LV1) at 1296 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 18816: speicherleistung 2585 speichersoc 40 (LV1) at 1291 loadvars loadvars.sh
2022-08-25 08:15:05: PID: 19431: EVU RET: 0 (LV2) at 15 main modules/bezug_smashm/main.sh
2022-08-25 08:15:05: PID: 2486: legacy run server: Completed running command in 0.17s: ["modules.sma_shm.device","counter","3007889034"]
2022-08-25 08:15:05: PID: 2486: modules.sma_shm.device: Update complete
2022-08-25 08:15:05: PID: 2486: modules.sma_shm.device: All components updated
2022-08-25 08:15:05: PID: 2486: soc.modules.common.store._api: Saving CounterState(voltages=[236.019, 235.983, 235.114], power_factors=[0.996, 0.998, 0.772], power=5.0, powers=[-601.1, 278.0, 328.1], exported=25721078.8, currents=[-2.845, 1.424, 2.079], imported=17967841.2, frequency=49.988)
2022-08-25 08:15:05: PID: 2486: soc.modules.common.component_context: Update Komponente ['SMA Home Manager Zähler']
2022-08-25 08:15:05: PID: 2486: soc.modules.common.component_context: Update Komponenten ['SMA Home Manager Zähler']
2022-08-25 08:15:05: PID: 2486: modules.sma_shm.device: Beginning update
2022-08-25 08:15:05: PID: 2486: legacy run server: Received command ["modules.sma_shm.device","counter","3007889034"]
2022-08-25 08:15:03: PID: 18805: ##### cron5min.sh finished ##### (LV0) at 432 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:03: PID: 19291: BattLeistung: 2585 (LV1) at 28 main modules/speicher_sbs25/main.sh
2022-08-25 08:15:03: PID: 19291: RET: 0 (LV2) at 24 main modules/speicher_sbs25/main.sh
2022-08-25 08:15:03: PID: 2486: legacy run server: Completed running command in 0.12s: ["modules.sma_sunny_boy.device","bat_smart_energy","192.168.178.124"]
2022-08-25 08:15:03: PID: 2486: soc.modules.common.store._api: Saving BatState(soc=40, imported=103012.88423513084, exported=100587.96444444444, power=2585.8415999999997)
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount Zwischenergebnisse aktuelle Berechnung: Import: 370846383.24647105 Export: 362116672 Leistung: 2585.8415999999997
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount Ergebnis: Bezug[Wh]: 103012.88423513084, Einspeisung[Wh]: 100587.96444444444
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount aufsummierte Energie: Bezug[Ws]: 370846383.24647105, Einspeisung[Ws]: 362116672
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount Gesamtenergie im Zeitintervall: 1801.246471048355
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount Berechnungsgrundlage: vergangene Zeit [s]0.694812536239624, vorherige Leistung[W]: 2599, aktuelle Leistung[W]: 2585.8415999999997
2022-08-25 08:15:03: PID: 2486: modules.common.simcount: simcount Zwischenergebnisse letzte Berechnung: Import: 370844582 Export: 362116672 Leistung: 2599
2022-08-25 08:15:03: PID: 2486: soc.modules.common.component_context: Update Komponente ['Sma Sunny Boy Smart Energy Speicher']
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: Start device reading {'componentNone': <modules.sma_sunny_boy.bat_smart_energy.SunnyBoySmartEnergyBat object at 0x72fb0770>}
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Hybrid: None
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Version: None
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus weitere IPs: None, None, None
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Webbox: None
2022-08-25 08:15:03: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Ip-Adresse: 192.168.178.124
2022-08-25 08:15:03: PID: 2486: legacy run server: Received command ["modules.sma_sunny_boy.device","bat_smart_energy","192.168.178.124"]
2022-08-25 08:15:03: PID: 2486: legacy run server: Completed running command in 0.06s: ["modules.solaredge.device","inverter","192.168.178.105","","1","none","none","none","0","0","0","0"
2022-08-25 08:15:03: PID: 2486: soc.modules.common.store._api: Saving InverterState(currents=[-0.9400000000000001, 0.0, 0.0], power=-160.41, exported=2391424.0)
2022-08-25 08:15:03: PID: 18805: logfile cleanup triggered (LV1) at 429 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:02: PID: 2486: soc.modules.common.component_context: Update Komponente ['Solaredge Wechselrichter']
2022-08-25 08:15:02: PID: 2486: modules.solaredge.device: Solaredge Bat-WR-gleiche IP: 0, Externer WR: 0, 2. Speicher: 0, Speicherleistung subtrahieren: 0 2. IP: none, Num: 2
2022-08-25 08:15:02: PID: 2486: modules.solaredge.device: Solaredge Slave-IDs: [1, none, none, none]
2022-08-25 08:15:02: PID: 2486: modules.solaredge.device: Solaredge IP: 192.168.178.105:
2022-08-25 08:15:02: PID: 2486: legacy run server: Received command ["modules.solaredge.device","inverter","192.168.178.105","","1","none","none","none","0","0","0","0"
2022-08-25 08:15:02: PID: 19217: RET: 0 (LV2) at 12 main modules/wr_tripower9000/main.sh
2022-08-25 08:15:02: PID: 2486: legacy run server: WR 1 nach Korrektur: InverterState(currents=[0.0, 0.0, 0.0], power=0, exported=452448)
2022-08-25 08:15:02: PID: 2486: legacy run server: Completed running command in 0.38s: ["modules.sma_sunny_boy.device","inverter","192.168.178.124","0","192.168.178.100","192.168.178.106"
2022-08-25 08:15:02: PID: 2486: soc.modules.common.store._api: Saving InverterState(currents=[0.0, 0.0, 0.0], power=-3561.9392000000003, exported=16683911)
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.inverter: WR InverterState(currents=[0.0, 0.0, 0.0], power=-650, exported=2137487): 192.168.178.106
2022-08-25 08:15:02: PID: 18805: starting evsecheck (LV1) at 425 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.inverter: WR InverterState(currents=[0.0, 0.0, 0.0], power=-312, exported=14093976): 192.168.178.100
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.inverter: WR InverterState(currents=[0.0, 0.0, 0.0], power=0, exported=452448): 192.168.178.124
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount Zwischenergebnisse aktuelle Berechnung: Import: 370844582.78949684 Export: 362116672 Leistung: 2599.9392000000003
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount Ergebnis: Bezug[Wh]: 103012.38410819357, Einspeisung[Wh]: 100587.96444444444
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount aufsummierte Energie: Bezug[Ws]: 370844582.78949684, Einspeisung[Ws]: 362116672
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount Gesamtenergie im Zeitintervall: 24485.78949684448
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount Berechnungsgrundlage: vergangene Zeit [s]9.349398136138916, vorherige Leistung[W]: 2638, aktuelle Leistung[W]: 2599.9392000000003
2022-08-25 08:15:02: PID: 2486: modules.common.simcount: simcount Zwischenergebnisse letzte Berechnung: Import: 370820097 Export: 362116672 Leistung: 2638
2022-08-25 08:15:02: PID: 18805: rse disabled (LV0) at 39 rseSetup /var/www/html/openWB/runs/rse/rseHelper.sh
2022-08-25 08:15:02: PID: 18805: push buttons disabled (LV0) at 39 pushButtonsSetup /var/www/html/openWB/runs/pushButtons/pushButtonsHelper.sh
2022-08-25 08:15:02: PID: 18805: modbus tcp server already running (LV1) at 401 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:02: PID: 2486: soc.modules.common.component_context: Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Hybrid: 1
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Version: 0
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus weitere IPs: 192.168.178.100, 192.168.178.106, none
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Webbox: 0
2022-08-25 08:15:02: PID: 2486: modules.sma_sunny_boy.device: SMA Modbus Ip-Adresse: 192.168.178.124
2022-08-25 08:15:02: PID: 2486: legacy run server: Received command ["modules.sma_sunny_boy.device","inverter","192.168.178.124","0","192.168.178.100","192.168.178.106"
2022-08-25 08:15:02: PID: 18805: external openWB or daemon mode not configured; checking network setup (LV1) at 359 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:02: PID: 18805: smarthomemq handler is already running (LV1) at 341 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: legacy_run_server is already running (LV1) at 315 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: mqtt handler is already running (LV1) at 302 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: current ip: 192.168.178.165 (LV1) at 297 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: updating daily yield stats (LV1) at 167 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: electricity provider not set up; skipping price update (LV2) at 163 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: Netzschutz konfiguriert; aktuelle Frequenz: 4990 (LV1) at 112 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: daily csv updated: /var/www/html/openWB/web/logging/data/daily/20220825.csv (LV1) at 104 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18805: ##### cron5min.sh started ##### (LV0) at 26 main /var/www/html/openWB/runs/cron5min.sh
2022-08-25 08:15:01: PID: 18816: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh

[JPRider]

Und noch einen Einflussfaktor auf die während der Ladephase zu hohe PV Leistung habe ich gerade erkannt. Schalten sich Verbraucher zu, wie im Graph als violette Linie ab 9:43 Uhr (Geschirrspüler) zusehen, wird die angezeigte PV Leistung im selben Maß reduziert.

demnach:
--> Batterieladung verfälscht PV Leistung und Hausverbrauch
--> separate gemessene Verbraucher verfälschen die PV Leistung aber nicht den Hausverbrauch (Grundlast)

Gilt aber nur für die Phase der Speicherladung! Danach passt es dann wieder.

[LenaK]

Kannst Du bitte das Log zu dieser Situation posten?

[JPRider]

Kannst Du bitte das Log zu dieser Situation posten?

Mache ich, bin aber erst heute abend wieder in meinem Heimnetz. Über die Cloud komme ich nicht an die Logdaten.