openWB Forum

Die modulare Wallbox
https://forum.openwb.de/

Rückmeldungen Beta 1.9.257 (RC1)

https://forum.openwb.de/viewtopic.php?t=4872

Seite 4 von 9

Re: Rückmeldungen Beta 1.9.257 (RC1) OT: DebugLog

Verfasst: Do Mär 03, 2022 5:56 pm
von Thomas aus W
HSC hat geschrieben: ↑Do Mär 03, 2022 2:41 pm Ich bin nun gezwungen, dass DebugLog zu "halbieren".
Gerüchten zu Folge kann man so ein Log auch in eine Text-Datei kopieren und die hier an das Post anhängen.. ;o)

bye
TW

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Fr Mär 04, 2022 2:03 pm
von mrinas
Seit dem Update auf die .257 beta habe ich an meinem Model Y gelegentlich kurze Ladeeinbrüche. Im Webinterface der openWB sehe ich dann 0W Ladleistung, die Ladestromvorgabe ist auf >20A.

Das Model Y ist an einer externen openWB angeschlossen, so sieht das dann aus:
Screenshot 2022-03-04 144717.png
Log openWB:

Code: Alles auswählen

2022-03-04 13:40:02: PID: 28048: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:40:03:  Load: 1.08 0.85 0.92
2022-03-04 13:40:03: PID: 28048: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:42:53: PID: 18711: Modbus EVSE read CP1 issue - using previous state '1' (LV0)
2022-03-04 13:42:59: PID: 18711: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 13:45:02: PID: 3749: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:45:03:  Load: 4.17 2.33 1.48
2022-03-04 13:45:03: PID: 3749: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:47:09: PID: 19800: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 13:50:01: PID: 11458: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:50:02:  Load: 3.15 2.35 1.69
2022-03-04 13:50:03: PID: 11458: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:51:19: PID: 21109: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 13:55:01: PID: 19337: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:55:02:  Load: 1.36 1.87 1.67
2022-03-04 13:55:03: PID: 19337: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:00:01: PID: 27378: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:00:02:  Load: 1.33 1.48 1.55
2022-03-04 14:00:03: PID: 27378: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:05:01: PID: 3097: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:05:02:  Load: 0.72 0.92 1.27
2022-03-04 14:05:03: PID: 3097: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:10:01: PID: 11215: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:10:02:  Load: 0.45 0.73 1.10
2022-03-04 14:10:03: PID: 11215: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:15:02: PID: 19249: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:15:03:  Load: 0.43 0.69 0.99
2022-03-04 14:15:03: PID: 19249: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:18:09: PID: 11359: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 14:20:02: PID: 27416: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:20:03:  Load: 0.86 0.79 0.95
2022-03-04 14:20:04: PID: 27416: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:23:19: PID: 20763: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 14:25:01: PID: 3157: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:25:02:  Load: 1.59 1.09 1.01
2022-03-04 14:25:03: PID: 3157: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:28:29: PID: 30180: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-04 14:30:01: PID: 11234: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:30:02:  Load: 2.98 2.42 1.62
2022-03-04 14:30:03: PID: 11234: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:35:01: PID: 18663: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:35:02:  Load: 1.28 2.04 1.72
2022-03-04 14:35:03: PID: 18663: ##### cron5min.sh finished ##### (LV0)

Log externe openWB:

Code: Alles auswählen

2022-03-04 13:35:03: PID: 21288: ##### cron5min.sh finished ##### (LV0)
Traceback (most recent call last):
  File "/var/www/html/openWB/runs/isss.py", line 944, in <module>
    loadregelvars()
  File "/var/www/html/openWB/runs/isss.py", line 802, in loadregelvars
    heartbeat = int(value.read())
ValueError: invalid literal for int() with base 10: ''
2022-03-04 13:40:02: PID: 22505: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:40:02: PID: 22505: isss handler not running! restarting process (LV0)
2022-03-04 13:40:03: PID: 22505: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:45:01: PID: 23698: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:45:03: PID: 23698: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:50:01: PID: 24861: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:50:03: PID: 24861: ##### cron5min.sh finished ##### (LV0)
2022-03-04 13:55:01: PID: 26047: ##### cron5min.sh started ##### (LV0)
2022-03-04 13:55:03: PID: 26047: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:00:01: PID: 27171: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:00:03: PID: 27171: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:05:01: PID: 28301: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:05:03: PID: 28301: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:10:01: PID: 29546: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:10:03: PID: 29546: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:15:01: PID: 30671: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:15:03: PID: 30671: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:20:01: PID: 31910: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:20:03: PID: 31910: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:25:01: PID: 630: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:25:03: PID: 630: ##### cron5min.sh finished ##### (LV0)
2022-03-04 14:30:01: PID: 1817: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:30:03: PID: 1817: ##### cron5min.sh finished ##### (LV0)
Traceback (most recent call last):
  File "/var/www/html/openWB/runs/isss.py", line 944, in <module>
    loadregelvars()
  File "/var/www/html/openWB/runs/isss.py", line 802, in loadregelvars
    heartbeat = int(value.read())
ValueError: invalid literal for int() with base 10: ''
2022-03-04 14:35:01: PID: 3028: ##### cron5min.sh started ##### (LV0)
2022-03-04 14:35:02: PID: 3028: isss handler not running! restarting process
Latestatus Log:

Code: Alles auswählen

2022-03-04 14:46:07: PID: 10937: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 8 Ampere (LV0)
2022-03-04 14:45:57: PID: 9581: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 8 Ampere (LV0)
2022-03-04 14:45:47: PID: 8251: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 8 Ampere (LV0)
2022-03-04 14:45:37: PID: 6929: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 7 Ampere (LV0)
2022-03-04 14:45:27: PID: 5602: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 6 Ampere (LV0)
2022-03-04 14:45:17: PID: 4247: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 20 Ampere (LV0)
2022-03-04 14:45:07: PID: 2559: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 18 Ampere (LV0)
2022-03-04 14:44:57: PID: 1140: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 9 Ampere (LV0)
2022-03-04 14:44:47: PID: 32219: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 9 Ampere (LV0)
2022-03-04 14:44:37: PID: 30893: alle Ladepunkte, Lademodus NurPV. Ladung geändert auf 9 Ampere (LV0)

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mo Mär 07, 2022 11:16 am
von SIDE22
LutzB hat geschrieben: ↑Do Mär 03, 2022 9:05 am
SIDE22 hat geschrieben: ↑Mi Mär 02, 2022 12:15 pm Aktuelle nightly 1.9.258
Immer noch Fehler beim Auslesen des Hausverbrauch
MPM3PM

Code: Alles auswählen

2022-03-02 13:10:00: PID: 3292: **** FATAL ********************************* (LV0)
2022-03-02 13:10:00: PID: 3292: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-03-02 13:10:00: PID: 3292: **** FATAL ********************************* (LV0)
  RequestsDependencyWarning)
/usr/lib/python3/dist-packages/requests/__init__.py:91: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (3.0.4) doesn't match a supported version!
  RequestsDependencyWarning)
/usr/lib/python3/dist-packages/requests/__init__.py:91: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (3.0.4) doesn't match a supported version!
Screenshot_20220302-131028683.jpg

LG
Florian
Die Angabe "aktuelle Nightly" hilft nicht wirklich weiter. Bitte immer die genaue Version mit Datum und Hash angeben. Der letzte Bugfix für das Modul war am 02.03.11:15.

Außerdem auch immer einen kompletten Durchlauf der Regelung aus dem Debug Log mit aktiviertem Debug Mode 2 posten. Mit den paar Zeilen kann man keine Ursachenforschung betreiben.
Sry hier einmal mit Mode 2

Code: Alles auswählen

2022-03-07 12:13:41: PID: 18256: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh
2022-03-07 12:13:39: PID: 16837: **** FATAL ********************************* (LV0) at 61 cleanup /var/www/html/openWB/regel.sh
2022-03-07 12:13:39: PID: 16837: **** FATAL Regulation loop needs 9 seconds (LV0) at 60 cleanup /var/www/html/openWB/regel.sh
2022-03-07 12:13:39: PID: 16837: **** FATAL ********************************* (LV0) at 59 cleanup /var/www/html/openWB/regel.sh
2022-03-07 12:13:39: PID: 16837: Überschuss 0; mindestens 700 (LV1) at 104 nurpvlademodus nurpv.sh
2022-03-07 12:13:39: PID: 16837: uberschuss 0 wattbezug 0 ladestatus 0 llsoll 0 pvwatt -2089 mindestuberschussphasen 700 wattkombiniert 0 schaltschwelle 230 (LV2) at 575 main /var/www/html/openWB/regel.sh
2022-03-07 12:13:39: PID: 16837: anzahlphasen 1 (LV1) at 574 main /var/www/html/openWB/regel.sh
  RequestsDependencyWarning)
/usr/lib/python3/dist-packages/requests/__init__.py:91: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (3.0.4) doesn't match a supported version!
2022-03-07 12:13:38: PID: 16837: Zeit zum abfragen aller Werte 6 Sekunden (LV1) at 176 main /var/www/html/openWB/regel.sh
2022-03-07 12:13:36: PID: 16837: chargestatlp1 0	chargestatlp2 0	chargestatlp3 0 (LV1) at 1275 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: plugstatlp1 1	plugstatlp2 0	plugstatlp3 0 (LV1) at 1274 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: lp1enabled 1	lp2enabled 1	lp3enabled 1 (LV1) at 1273 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: EVU 1:V/1A 2: V/2A 3: V/6A (LV1) at 1272 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: lla3 0	llv3 229.0	llas13 0 llas23  soclp1 0 soclp2 90 (LV1) at 1271 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: lla2 0	llv2 230.5	llas12 0 llas22  sofortll 10 hausverbrauch 2089 wattbezug 0 uberschuss 0 (LV1) at 1270 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: lla1 0	llv1 230.2	llas11 0 llas21  mindestuberschuss 700 abschaltuberschuss 400 lademodus 2 (LV1) at 1269 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 16837: pv1watt -1727 pv2watt -362 pvwatt -2089 ladeleistung 0 llalt 0 nachtladen 0 nachtladen 1 minimalA 6 maximalA 32 (LV1) at 1268 loadvars loadvars.sh
2022-03-07 12:13:36: PID: 17190: EVU RET: 0 (LV2) at 26 main modules/bezug_mpm3pm/main.sh
2022-03-07 12:13:36: PID: 23334: legacy run server: Completed running command in 0.71s: ["bezug_mpm3pm.readmpm3pm","/dev/ttyUSB0","5"]
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x8 0x0 0x0 0x13 0x88 0x2 0x20 0x0 0x5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x8 0x0 0x0 0x13 0x88 0x2 0x20 0x0 0x5 0x13 0xf0
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651616.211872, Current Time stamp - 1646651616.217328
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x2c 0x0 0x4 0x31 0x84
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Running transaction 13
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x8 0x0 0x11 0xba 0xcb 0x0 0x10 0x5b 0xa4
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x8 0x0 0x11 0xba 0xcb 0x0 0x10 0x5b 0xa4 0xb4 0x38
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651616.145489, Current Time stamp - 1646651616.150816
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x4 0x0 0x4 0xb1 0x8c
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Running transaction 12
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0x0 0x1 0xc 0xf2
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0x0 0x1 0xc 0xf2 0x6b 0x1
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651616.096678, Current Time stamp - 1646651616.1022
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x26 0x0 0x2 0x91 0x84
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Running transaction 11
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0x0 0x2 0x69 0x44
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0x0 0x2 0x69 0x44 0x31 0xe7
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651616.048942, Current Time stamp - 1646651616.05386
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x18 0x0 0x2 0xf0 0x48
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Running transaction 10
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0xff 0xff 0x30 0xc6
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0xff 0xff 0x30 0xc6 0x2a 0x32
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:36: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651616.00134, Current Time stamp - 1646651616.006297
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x16 0x0 0x2 0x91 0x8b
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Running transaction 9
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:36: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:36: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0xff 0xff 0x7f 0xa4
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0xff 0xff 0x7f 0xa4 0x9f 0xeb
2022-03-07 12:13:36: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651615.954707, Current Time stamp - 1646651615.958095
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x14 0x0 0x2 0x30 0x4b
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Running transaction 8
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:35: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0x0 0x0 0x2 0xaa
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0x0 0x0 0x2 0xaa 0x3f 0x5b
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651615.909596, Current Time stamp - 1646651615.913156
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x12 0x0 0x2 0xd0 0x4a
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Running transaction 7
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:35: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0x0 0x0 0x1 0x6
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0x0 0x0 0x1 0x6 0x3f 0xd6
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651615.864451, Current Time stamp - 1646651615.86782
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x10 0x0 0x2 0x71 0x8a
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Running transaction 6
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:35: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x4 0x0 0x0 0x0 0xc5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x4 0x0 0x0 0x0 0xc5 0x7e 0x17
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651615.818096, Current Time stamp - 1646651615.821661
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0xe 0x0 0x2 0x11 0x8c
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Running transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Getting transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Adding transaction 5
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Frame advanced, resetting header!!
2022-03-07 12:13:35: PID: 23334: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Getting Frame - 0x4 0x8 0x0 0x20 0x6b 0x65 0x0 0x11 0xba 0xcb
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: RECV: 0x5 0x4 0x8 0x0 0x20 0x6b 0x65 0x0 0x11 0xba 0xcb 0x36 0xde
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-03-07 12:13:35: PID: 23334: pymodbus.client.sync: New Transaction state 'SENDING'
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Waiting for 3.5 char before next send - 4.01 ms
2022-03-07 12:13:35: PID: 23334: pymodbus.framer.rtu_framer: Changing state to IDLE - Last Frame End - 1646651615.75422, Current Time stamp - 1646651615.757656
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: SEND: 0x5 0x4 0x0 0x2 0x0 0x4 0x51 0x8d
2022-03-07 12:13:35: PID: 23334: pymodbus.transaction: Running transaction 4

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mo Mär 07, 2022 5:09 pm
von vonhof
EVU via Victron erzeugt viele solcher Einträge:

/usr/lib/python3/dist-packages/requests/__init__.py:91: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (3.0.4) doesn't match a supported version!

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mo Mär 07, 2022 5:17 pm
von vonhof
Oh je, das war wohl nichts. Kann man das log einfach löschen, also openWB.log komplett löschen? Wird dann vom Programm wieder neu angelegt? oder wie gehe ich vor?

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Di Mär 08, 2022 11:41 pm
von jss
Bei mir besteht leider weiterhin das Problem, dass die Tageswerte für Im-/Export immer bei 0 bleiben und der Tageswert des Hausverbrauchs nicht stimmt (entspricht immer PV-Tageswert - Ladeleistung-Tageswert).
  • installierte Version: 1.9.258 (2022-03-02 19:55:20 +0100 [c7791c69] )
  • Kostal Plenticore 8.5 Plus + KSEM
OpenWB_KostalProblem_5.png
Ich hatte das auch schon im Thread für Version 1.9.254 beschrieben (viewtopic.php?p=59060#p59060). Hier auch nochmal der Logauszug aus meinem dortigen Post. Ich weiß nicht wie viel vom Log hier noch gebraucht wird - wenn ich das komplette Log kopieren will stürtzt mein Firefox ab, aber ich habe zumindest noch einen kleinen Teil als Datei angehängt, da es sonst zu viele Zeilen für das Forum sind.

Code: Alles auswählen

2022-02-25 02:58:52: PID: 3240: **** Regulation loop needs 1 seconds (LV2) at 55 cleanup /var/www/html/openWB/regel.sh
2022-02-25 02:58:51: PID: 3240: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh
2022-02-25 02:58:46: PID: 2011: **** Regulation loop needs 5 seconds (LV2) at 55 cleanup /var/www/html/openWB/regel.sh
2022-02-25 02:58:46: PID: 2011: Überschuss -307; mindestens 1300 (LV1) at 104 nurpvlademodus nurpv.sh
2022-02-25 02:58:46: PID: 2011: uberschuss -307 wattbezug 307 ladestatus 0 llsoll 0 pvwatt 0 mindestuberschussphasen 1300 wattkombiniert -307 schaltschwelle 230 (LV2) at 575 main /var/www/html/openWB/regel.sh
2022-02-25 02:58:46: PID: 2011: anzahlphasen 1 (LV1) at 574 main /var/www/html/openWB/regel.sh
2022-02-25 02:58:45: PID: 2011: Zeit zum abfragen aller Werte 3 Sekunden (LV1) at 176 main /var/www/html/openWB/regel.sh
2022-02-25 02:58:44: PID: 2011: chargestatlp1 0	chargestatlp2 0	chargestatlp3 0 (LV1) at 1275 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: plugstatlp1 0	plugstatlp2 0	plugstatlp3 0 (LV1) at 1274 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: lp1enabled 1	lp2enabled 1	lp3enabled 1 (LV1) at 1273 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: EVU 1:V/1A 2: V/0A 3: V/0A (LV1) at 1272 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: lla3 0	llv3 237.0	llas13  llas23  soclp1 43 soclp2 (LV1) at 1271 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: lla2 0	llv2 238.6	llas12  llas22  sofortll 16 hausverbrauch 307 wattbezug 307 uberschuss -307 (LV1) at 1270 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: lla1 0	llv1 236.6	llas11  llas21  mindestuberschuss 1300 abschaltuberschuss -100 lademodus 2 (LV1) at 1269 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2011: pv1watt 0 pv2watt  pvwatt 0 ladeleistung 0 llalt 0 nachtladen 0 nachtladen 0 minimalA 10 maximalA 16 (LV1) at 1268 loadvars loadvars.sh
2022-02-25 02:58:44: PID: 2168: RET: 0 (LV2) at 21 main modules/bezug_kostalplenticoreem300haus/main.sh
2022-02-25 02:58:44: PID: 2175: Kostal Plenticore Haus: 1
2022-02-25 02:58:43: PID: 2140: read_kostalplenticore.py:WR1 Leistung = 0PV_total = 0
 -Battery:0 -WR3:none-WR4:none-WR5:none
2022-02-25 02:58:43: PID: 2140: read_kostalplenticore.py:Wechselrichter Kostal Plenticore Config - WR1:192.168.178.58 -WR2:none
2022-02-25 02:58:41: PID: 2011: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh
OpenWBLog.txt
(503.26 KiB) 128-mal heruntergeladen

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mi Mär 09, 2022 5:36 am
von HSC
Probier mal als EVU das "KSEM oder TQ..", wenn es über Netzwerk angebunden ist, und ggf. den Tageswechsel abwarten. Im Status sollten sofort die direkten Zählerwerte aus KSEM kommen. Diese sind die Grundlage für die Tagessummen in Klammern.
Danach kannst Du auch wieder zurück.
VG

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mi Mär 09, 2022 10:36 am
von sagibo
Hallo zusammen,

bei der Version 1.9.258 (2022-03-05 18:05:20 +0100 [efb13b9d] )
treten bei meiner Installation wieder Laufzeitwarnungen auf. Nach dem letzten Fix diesbzgl. lief erstmal alles wieder 100% geschmeidig.
Jetzt treten aber nur "Warnings" auf.

Viele Grüße

Code: Alles auswählen

2022-03-09 10:55:02: PID: 3698: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:54:39: PID: 31929: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:50:04: PID: 26559: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:50:02: PID: 26559: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:49:49: PID: 23752: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:45:04: PID: 16973: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:45:02: PID: 16973: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:40:04: PID: 7397: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:40:02: PID: 7397: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:35:49: PID: 3541: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:35:03: PID: 30177: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:35:01: PID: 30177: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:34:49: PID: 27366: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:30:03: PID: 20610: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:30:01: PID: 20610: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:26:09: PID: 19628: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:25:03: PID: 11040: ##### cron5min.sh finished ##### (LV0)
2022-03-09 10:25:01: PID: 11040: ##### cron5min.sh started ##### (LV0)
2022-03-09 10:24:29: PID: 5447: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-03-09 10:20:03: PID: 1457: ##### cron5min.sh finished ##### (LV0)

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Mi Mär 09, 2022 1:28 pm
von Franknav
Hallo Zusammen,

ich habe festgestellt das auf der Version 1.9.258 in der Cloudversion der PKW-SOC zwar in der Grafik als Linie angezeigt wird (51%), aber leider nicht unten in der Ladepunktdarstellung. Leider wird der Ladepunktname auch nicht übernommen.
In der lokalen Version funktioniert es einwandfrei.

Gruß Frank

Re: Rückmeldungen Beta 1.9.257 (RC1)

Verfasst: Do Mär 10, 2022 7:45 am
von jss
HSC hat geschrieben: ↑Mi Mär 09, 2022 5:36 am Probier mal als EVU das "KSEM oder TQ..", wenn es über Netzwerk angebunden ist, und ggf. den Tageswechsel abwarten. Im Status sollten sofort die direkten Zählerwerte aus KSEM kommen. Diese sind die Grundlage für die Tagessummen in Klammern.
Danach kannst Du auch wieder zurück.
VG
Danke für den Tipp!
Habe das KSEM jetzt mal kurzfristig direkt ins Netzwerk eingebunden und nach dem Tageswechsel werden wieder Tageswerte angezeigt, die sinnvoll aussehen.
Alle Zeiten sind UTC
Seite 4 von 9

Powered by phpBB® Forum Software © phpBB Limited

Deutsche Übersetzung durch phpBB.de