1.9.301.1: FATAL Regulation loop needs XX seconds?
Verfasst: Mo Mai 29, 2023 4:36 pm
Moin,
habe seit einiger Zeit wieder mal ins Debug-Log geschaut und festgestellt, dass es voll mit derartigen Meldungen ist "FATAL Regulation loop needs 14 seconds". Ich habe nun das höchste Debüt-Level eingestellt, auf die 1.9.301.1 upgedated (openWB series2 Duo), aber keine Änderung. Anbei mal ein Auszug aus dem Log:
Ich erkenne nicht, woran das liegen könnte. Seht ihr da was, was nicht korrekt funktioniert und diese Verzögerungen verursacht?
Gruß
Michael
habe seit einiger Zeit wieder mal ins Debug-Log geschaut und festgestellt, dass es voll mit derartigen Meldungen ist "FATAL Regulation loop needs 14 seconds". Ich habe nun das höchste Debüt-Level eingestellt, auf die 1.9.301.1 upgedated (openWB series2 Duo), aber keine Änderung. Anbei mal ein Auszug aus dem Log:
Code: Alles auswählen
2023-05-29 18:27:01: PID: 11686: Previous regulation loop still running. Skipping. (LV0) at 35 main /var/www/html/openWB/regel.sh
10242
set-currents: setze ladung auf 6A
2023-05-29 18:26:58: PID: 10242: uberschuss 6711 wattbezug -6711 ladestatus 1 llsoll 6 pvwatt -7540 mindestuberschussphasen 1300 wattkombiniert 6711 schaltschwelle 230 (LV2) at 586 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:58: PID: 10242: anzahlphasen 1 (LV1) at 585 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:57: PID: 10242: Zeit zum abfragen aller Werte 6 Sekunden (LV1) at 186 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:56: PID: 10242: chargestatlp1 0 chargestatlp2 0 chargestatlp3 0 (LV1) at 1274 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: plugstatlp1 1 plugstatlp2 0 plugstatlp3 0 (LV1) at 1273 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: lp1enabled 1 lp2enabled 1 lp3enabled 1 (LV1) at 1272 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: EVU 1:V/10A 2: V/8A 3: V/9A (LV1) at 1271 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: lla3 0 llv3 239.2 llas13 0 llas23 soclp1 90 soclp2 0 (LV1) at 1270 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: lla2 0 llv2 238.6 llas12 0 llas22 sofortll 16 hausverbrauch 829 wattbezug -6711 uberschuss 6711 (LV1) at 1269 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: lla1 0 llv1 239.6 llas11 0 llas21 mindestuberschuss 1300 abschaltuberschuss 0 lademodus 2 (LV1) at 1268 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: pv1watt -5877 pv2watt -1663 pvwatt -7540 ladeleistung 0 llalt 6 nachtladen 0 nachtladen 0 minimalA 6 maximalA 32 (LV1) at 1267 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: etproviderprice etprovidermaxprice (LV1) at 1265 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10242: speicherleistung 0 speichersoc 100 (LV1) at 1262 loadvars loadvars.sh
2023-05-29 18:26:56: PID: 10532: RET: 0 (LV2) at 16 main modules/bezug_kostalplenticoreem300haus/main.sh
2023-05-29 18:26:56: PID: 21620: legacy run server: Completed running command in 0.01s: ["bezug_kostalplenticoreem300haus.kostal_plenticore","1"]
2023-05-29 18:26:56: PID: 21620: Kostal-Plenticore: Kostal Plenticore Haus: 1
2023-05-29 18:26:56: PID: 21620: legacy run server: Received command ["bezug_kostalplenticoreem300haus.kostal_plenticore","1"]
DUo2/dev/ttyUSB0idadd106
2023-05-29 18:26:54: PID: 10477: BattLeistung: 0 (LV1) at 19 main modules/speicher_kostalplenticore/main.sh
2023-05-29 18:26:54: PID: 10477: RET: 0 (LV2) at 17 main modules/speicher_kostalplenticore/main.sh
2023-05-29 18:26:54: PID: 21620: legacy run server: Completed running command in 0.00s: ["speicher_kostalplenticore.kostal_plenticore"]
2023-05-29 18:26:54: PID: 21620: legacy run server: Received command ["speicher_kostalplenticore.kostal_plenticore"]
2023-05-29 18:26:54: PID: 21620: legacy run server: Completed running command in 0.74s: ["modules.devices.json.device","inverter","http://192.168.30.6/solar_api/v1/GetPowerFlowRealtimeData
2023-05-29 18:26:54: PID: 21620: soc.modules.common.store._api: Saving InverterState(currents=[0.0, 0.0, 0.0], dc_power=None, power=-1663.0, exported=7614320.0)
}
}
"Timestamp" : "2023-05-29T18:26:53+02:00"
},
"UserMessage" : ""
"Reason" : "",
"Code" : 0,
"Status" : {
"RequestArguments" : {},
"Head" : {
},
}
"Version" : "12"
},
"rel_SelfConsumption" : null
"rel_Autonomy" : null,
"P_PV" : 1663,
"P_Load" : null,
"P_Grid" : null,
"P_Akku" : null,
"Mode" : "produce-only",
"Meter_Location" : "unknown",
"E_Year" : 1870281.625,
"E_Total" : 7614320,
"E_Day" : 39560,
"Site" : {
},
}
"P" : 1663
"E_Year" : 1870281.625,
"E_Total" : 7614320,
"E_Day" : 39560,
"DT" : 105,
"1" : {
"Inverters" : {
"Data" : {
"Body" : {
2023-05-29 18:26:53: PID: 21620: soc.modules.common.req: Get-Response: {
2023-05-29 18:26:53: PID: 21620: requests.packages.urllib3.connectionpool: http://192.168.30.6:80 "GET /solar_api/v1/GetPowerFlowRealtimeData.fcgi HTTP/1.1" 200 904
2023-05-29 18:26:53: PID: 21620: requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): 192.168.30.6:80
2023-05-29 18:26:53: PID: 21620: soc.modules.common.component_context: Update Komponenten ['Json Wechselrichter']
2023-05-29 18:26:53: PID: 21620: legacy run server: Received command ["modules.devices.json.device","inverter","http://192.168.30.6/solar_api/v1/GetPowerFlowRealtimeData
2023-05-29 18:26:53: PID: 21620: legacy run server: Completed running command in 0.45s: ["wr_plenticore.read_kostalplenticore","192.168.30.2","192.168.30.3","1","none"]
2023-05-29 18:26:53: PID: 21620: Kostal-Plenticore: WR2 Leistung = 2927PV_total = 5877
2023-05-29 18:26:53: PID: 21620: Kostal-Plenticore: WR1 Leistung = 2950PV_total = 2950
-Battery:1 -WR3:none-WR4:none-WR5:none
2023-05-29 18:26:53: PID: 21620: Kostal-Plenticore: Wechselrichter Kostal Plenticore Config - WR1:192.168.30.2 -WR2:192.168.30.3
2023-05-29 18:26:53: PID: 21620: legacy run server: Received command ["wr_plenticore.read_kostalplenticore","192.168.30.2","192.168.30.3","1","none"]
2023-05-29 18:26:51: PID: 10242: **** Regulation loop start **** (LV1) at 77 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:44: PID: 8773: **** FATAL ********************************* (LV0) at 68 cleanup /var/www/html/openWB/regel.sh
2023-05-29 18:26:44: PID: 8773: **** FATAL Regulation loop needs 13 seconds (LV0) at 67 cleanup /var/www/html/openWB/regel.sh
2023-05-29 18:26:44: PID: 8773: **** FATAL ********************************* (LV0) at 66 cleanup /var/www/html/openWB/regel.sh
set-currents: setze ladung auf 12A
2023-05-29 18:26:41: PID: 10182: Previous regulation loop still running. Skipping. (LV0) at 35 main /var/www/html/openWB/regel.sh
8773
set-currents: setze ladung auf 6A
2023-05-29 18:26:38: PID: 8773: uberschuss 7119 wattbezug -7119 ladestatus 1 llsoll 6 pvwatt -7752 mindestuberschussphasen 1300 wattkombiniert 7119 schaltschwelle 230 (LV2) at 586 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:38: PID: 8773: anzahlphasen 1 (LV1) at 585 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:37: PID: 8773: Zeit zum abfragen aller Werte 6 Sekunden (LV1) at 186 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:36: PID: 8773: chargestatlp1 0 chargestatlp2 0 chargestatlp3 0 (LV1) at 1274 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: plugstatlp1 1 plugstatlp2 0 plugstatlp3 0 (LV1) at 1273 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: lp1enabled 1 lp2enabled 1 lp3enabled 1 (LV1) at 1272 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: EVU 1:V/10A 2: V/9A 3: V/9A (LV1) at 1271 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: lla3 0 llv3 239.2 llas13 0 llas23 soclp1 90 soclp2 0 (LV1) at 1270 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: lla2 0 llv2 238.8 llas12 0 llas22 sofortll 16 hausverbrauch 633 wattbezug -7119 uberschuss 7119 (LV1) at 1269 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: lla1 0 llv1 239.6 llas11 0 llas21 mindestuberschuss 1300 abschaltuberschuss 0 lademodus 2 (LV1) at 1268 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: pv1watt -6031 pv2watt -1721 pvwatt -7752 ladeleistung 0 llalt 6 nachtladen 0 nachtladen 0 minimalA 6 maximalA 32 (LV1) at 1267 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: etproviderprice etprovidermaxprice (LV1) at 1265 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 8773: speicherleistung 0 speichersoc 100 (LV1) at 1262 loadvars loadvars.sh
2023-05-29 18:26:36: PID: 9063: RET: 0 (LV2) at 16 main modules/bezug_kostalplenticoreem300haus/main.sh
2023-05-29 18:26:36: PID: 21620: legacy run server: Completed running command in 0.01s: ["bezug_kostalplenticoreem300haus.kostal_plenticore","1"]
2023-05-29 18:26:36: PID: 21620: Kostal-Plenticore: Kostal Plenticore Haus: 1
2023-05-29 18:26:36: PID: 21620: legacy run server: Received command ["bezug_kostalplenticoreem300haus.kostal_plenticore","1"]
DUo2/dev/ttyUSB0idadd106
2023-05-29 18:26:34: PID: 9008: BattLeistung: 0 (LV1) at 19 main modules/speicher_kostalplenticore/main.sh
2023-05-29 18:26:34: PID: 9008: RET: 0 (LV2) at 17 main modules/speicher_kostalplenticore/main.sh
2023-05-29 18:26:34: PID: 21620: legacy run server: Completed running command in 0.00s: ["speicher_kostalplenticore.kostal_plenticore"]
2023-05-29 18:26:34: PID: 21620: legacy run server: Received command ["speicher_kostalplenticore.kostal_plenticore"]
2023-05-29 18:26:34: PID: 21620: legacy run server: Completed running command in 0.78s: ["modules.devices.json.device","inverter","http://192.168.30.6/solar_api/v1/GetPowerFlowRealtimeData
2023-05-29 18:26:34: PID: 21620: soc.modules.common.store._api: Saving InverterState(currents=[0.0, 0.0, 0.0], dc_power=None, power=-1721.0, exported=7614309.5)
}
}
"Timestamp" : "2023-05-29T18:26:33+02:00"
},
"UserMessage" : ""
"Reason" : "",
"Code" : 0,
"Status" : {
"RequestArguments" : {},
"Head" : {
},
}
"Version" : "12"
},
"rel_SelfConsumption" : null
"rel_Autonomy" : null,
"P_PV" : 1721,
"P_Load" : null,
"P_Grid" : null,
"P_Akku" : null,
"Mode" : "produce-only",
"Meter_Location" : "unknown",
"E_Year" : 1870271.875,
"E_Total" : 7614309.5,
"E_Day" : 39550,
"Site" : {
},
}
"P" : 1721
"E_Year" : 1870271.875,
"E_Total" : 7614309.5,
"E_Day" : 39550,
"DT" : 105,
"1" : {
"Inverters" : {
"Data" : {
"Body" : {
2023-05-29 18:26:33: PID: 21620: soc.modules.common.req: Get-Response: {
2023-05-29 18:26:33: PID: 21620: requests.packages.urllib3.connectionpool: http://192.168.30.6:80 "GET /solar_api/v1/GetPowerFlowRealtimeData.fcgi HTTP/1.1" 200 908
2023-05-29 18:26:33: PID: 21620: requests.packages.urllib3.connectionpool: Starting new HTTP connection (1): 192.168.30.6:80
2023-05-29 18:26:33: PID: 21620: soc.modules.common.component_context: Update Komponenten ['Json Wechselrichter']
2023-05-29 18:26:33: PID: 21620: legacy run server: Received command ["modules.devices.json.device","inverter","http://192.168.30.6/solar_api/v1/GetPowerFlowRealtimeData
2023-05-29 18:26:33: PID: 21620: legacy run server: Completed running command in 0.35s: ["wr_plenticore.read_kostalplenticore","192.168.30.2","192.168.30.3","1","none"]
2023-05-29 18:26:33: PID: 21620: Kostal-Plenticore: WR2 Leistung = 2998PV_total = 6031
2023-05-29 18:26:33: PID: 21620: Kostal-Plenticore: WR1 Leistung = 3033PV_total = 3033
-Battery:1 -WR3:none-WR4:none-WR5:none
2023-05-29 18:26:33: PID: 21620: Kostal-Plenticore: Wechselrichter Kostal Plenticore Config - WR1:192.168.30.2 -WR2:192.168.30.3
2023-05-29 18:26:33: PID: 21620: legacy run server: Received command ["wr_plenticore.read_kostalplenticore","192.168.30.2","192.168.30.3","1","none"]
2023-05-29 18:26:31: PID: 8773: **** Regulation loop start **** (LV1) at 77 main /var/www/html/openWB/regel.sh
2023-05-29 18:26:24: PID: 7302: **** FATAL ********************************* (LV0) at 68 cleanup /var/www/html/openWB/regel.sh
2023-05-29 18:26:24: PID: 7302: **** FATAL Regulation loop needs 13 seconds (LV0) at 67 cleanup /var/www/html/openWB/regel.sh
2023-05-29 18:26:24: PID: 7302: **** FATAL ********************************* (LV0) at 66 cleanup /var/www/html/openWB/regel.sh
set-currents: setze ladung auf 12A
2023-05-29 18:26:21: PID: 8711: Previous regulation loop still running. Skipping. (LV0) at 35 main /var/www/html/openWB/regel.sh
7302Gruß
Michael