EVU-Kit MPM3PM keine Daten

[evchab]

bekomme mit der aktuellen 250er keine plausiblen Daten vom EVU-Kit

nach den Problemen mit Huawei als EVU und PV Datenlieferant habe ich mal testweise auf EVU-Kit MPM3PM umgestellt

Code: Alles auswählen

2022-01-04 11:28:38: **** Regulation loop needs 7 seconds (LV0) at 55 cleanup /var/www/html/openWB/regel.sh
2022-01-04 11:28:38: Überschuss 0; mindestens 1400 (LV1) at 104 nurpvlademodus nurpv.sh
2022-01-04 11:28:38: uberschuss 0 wattbezug 0 ladestatus 0 llsoll 0 pvwatt 0 mindestuberschussphasen 1400 wattkombiniert 0 schaltschwelle -25 (LV2) at 578 main /var/www/html/openWB/regel.sh
2022-01-04 11:28:38: anzahlphasen 1 (LV1) at 577 main /var/www/html/openWB/regel.sh
2022-01-04 11:28:37: Timing Umschaltung: 240 / 720 (LV1) at 15 u1p3pswitch u1p3p.sh
2022-01-04 11:28:37: automatische Umschaltung aktiv (LV1) at 14 u1p3pswitch u1p3p.sh
2022-01-04 11:28:36: chargestatlp1 0	chargestatlp2 0	chargestatlp3 0 (LV1) at 1284 loadvars loadvars.sh
2022-01-04 11:28:36: plugstatlp1 0	plugstatlp2 0	plugstatlp3 0 (LV1) at 1283 loadvars loadvars.sh
2022-01-04 11:28:36: lp1enabled 1	lp2enabled 1	lp3enabled 1 (LV1) at 1282 loadvars loadvars.sh
2022-01-04 11:28:36: EVU 1:V/1A 2: V/1A 3: V/0A (LV1) at 1281 loadvars loadvars.sh
2022-01-04 11:28:36: lla3 0	llv3 235.0	llas13  llas23  soclp1 55 soclp2 (LV1) at 1280 loadvars loadvars.sh
2022-01-04 11:28:36: lla2 0	llv2 235.0	llas12  llas22  sofortll 32 hausverbrauch 0 wattbezug 0 uberschuss 0 (LV1) at 1279 loadvars loadvars.sh
2022-01-04 11:28:36: lla1 0	llv1 234.3	llas11  llas21  mindestuberschuss 1400 abschaltuberschuss 5 lademodus 2 (LV1) at 1278 loadvars loadvars.sh
2022-01-04 11:28:36: pv1watt  pv2watt  pvwatt 0 ladeleistung 0 llalt 0 nachtladen 0 nachtladen 0 minimalA 6 maximalA 32 (LV1) at 1277 loadvars loadvars.sh
2022-01-04 11:28:36: EVU RET: 0 (LV2) at 21 main modules/bezug_ethmpm3pm/main.sh
2022-01-04 11:28:36: PID: 26236: root: EVU Einspeisung: 36746666449381780
2022-01-04 11:28:36: PID: 26236: root: EVU Bezug: 42959937940393190
2022-01-04 11:28:36: PID: 26236: root: EVU Watt: 1.1421983782711584e-42
2022-01-04 11:28:36: PID: 26236: root: EVU-Kit Leistung[W]: 1.1421983782711584e-42
2022-01-04 11:28:36: PID: 26236: root: Close Modbus TCP connection
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [b'\x00\r', b'\x0e\x17', b'\x00\n', b'\x1d\xc2']
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [13, 3607, 10, 7618]
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Getting transaction 7
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Adding transaction 7
2022-01-04 11:28:36: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:36: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x7 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0xd 0xe 0x17 0x0 0xa 0x1d 0xc2
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: RECV: 0x0 0x7 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0xd 0xe 0x17 0x0 0xa 0x1d 0xc2
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:36: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: SEND: 0x0 0x7 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x4 0x0 0x4
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Running transaction 7
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [b'\x00\x0f', b'C/', b'\x00\r', b'\x0e\x17']
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [15, 17199, 13, 3607]
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Getting transaction 6
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Adding transaction 6
2022-01-04 11:28:36: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:36: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x6 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0xf 0x43 0x2f 0x0 0xd 0xe 0x17
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: RECV: 0x0 0x6 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0xf 0x43 0x2f 0x0 0xd 0xe 0x17
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:36: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: SEND: 0x0 0x6 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x2 0x0 0x4
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Running transaction 6
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\x03\xde']
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\x03\x9f']
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\x02\xcf']
2022-01-04 11:28:36: PID: 26236: pymodbus.payload: [0, 719, 0, 927, 0, 990]
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Getting transaction 5
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Adding transaction 5
2022-01-04 11:28:36: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:36: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x5 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x2 0xcf 0x0 0x0 0x3 0x9f 0x0 0x0 0x3 0xde
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: RECV: 0x0 0x5 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x2 0xcf 0x0 0x0 0x3 0x9f 0x0 0x0 0x3 0xde
2022-01-04 11:28:36: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: SEND: 0x0 0x5 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x20 0x0 0x6
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Running transaction 5
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\x13\x88', b'\x02 ', b'\x00\x05']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [0, 5000, 544, 5]
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Getting transaction 4
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Adding transaction 4
2022-01-04 11:28:35: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:35: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x4 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0x0 0x13 0x88 0x2 0x20 0x0 0x5
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: RECV: 0x0 0x4 0x0 0x0 0x0 0xb 0x5 0x4 0x8 0x0 0x0 0x13 0x88 0x2 0x20 0x0 0x5
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: SEND: 0x0 0x4 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x2c 0x0 0x4
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Running transaction 4
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x01', b'>f']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [1, 15974]
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Getting transaction 3
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Adding transaction 3
2022-01-04 11:28:35: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:35: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x3 0x0 0x0 0x0 0x7 0x5 0x4 0x4 0x0 0x1 0x3e 0x66
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: RECV: 0x0 0x3 0x0 0x0 0x0 0x7 0x5 0x4 0x4 0x0 0x1 0x3e 0x66
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: SEND: 0x0 0x3 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x26 0x0 0x2
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Running transaction 3
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'*0']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\xa6T']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'm.']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [0, 27950, 0, 42580, 0, 10800]
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Getting transaction 2
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Adding transaction 2
2022-01-04 11:28:35: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:35: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x2 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x6d 0x2e 0x0 0x0 0xa6 0x54 0x0 0x0 0x2a 0x30
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: RECV: 0x0 0x2 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x6d 0x2e 0x0 0x0 0xa6 0x54 0x0 0x0 0x2a 0x30
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: SEND: 0x0 0x2 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x14 0x0 0x6
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Running transaction 2
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\t*']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b'\t/']
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [b'\x00\x00', b"\t'"]
2022-01-04 11:28:35: PID: 26236: pymodbus.payload: [0, 2343, 0, 2351, 0, 2346]
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Getting transaction 1
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Adding transaction 1
2022-01-04 11:28:35: PID: 26236: pymodbus.factory: Factory Response[ReadInputRegistersResponse: 4]
2022-01-04 11:28:35: PID: 26236: pymodbus.framer.socket_framer: Processing: 0x0 0x1 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x9 0x27 0x0 0x0 0x9 0x2f 0x0 0x0 0x9 0x2a
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: RECV: 0x0 0x1 0x0 0x0 0x0 0xf 0x5 0x4 0xc 0x0 0x0 0x9 0x27 0x0 0x0 0x9 0x2f 0x0 0x0 0x9 0x2a
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-01-04 11:28:35: PID: 26236: pymodbus.client.sync: New Transaction state 'SENDING'
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: SEND: 0x0 0x1 0x0 0x0 0x0 0x6 0x5 0x4 0x0 0x8 0x0 0x6
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Running transaction 1
2022-01-04 11:28:35: PID: 26236: pymodbus.transaction: Current transaction state - IDLE
2022-01-04 11:28:35: PID: 26236: root: Start kit reading
2022-01-04 11:28:35: PID: 26236: root: Start device reading {'componentNone': <modules.openwb.counter.EvuKit object at 0x75b5b4b0>}
2022-01-04 11:28:35: PID: 26236: root: openWB Version: 0
2022-01-04 11:28:31: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh
2022-01-04 11:28:28: **** Regulation loop needs 7 seconds (LV0) at 55 cleanup /var/www/html/openWB/regel.sh
2022-01-04 11:28:28: Überschuss 0; mindestens 1400 (LV1) at 104 nurpvlademodus nurpv.sh
2022-01-04 11:28:28: uberschuss 0 wattbezug 0 ladestatus 0 llsoll 0 pvwatt 0 mindestuberschussphasen 1400 wattkombiniert 0 schaltschwelle -25 (LV2) at 578 main /var/www/html/openWB/regel.sh
2022-01-04 11:28:28: anzahlphasen 1 (LV1) at 577 main /var/www/html/openWB/regel.sh
2022-01-04 11:28:27: Timing Umschaltung: 240 / 720 (LV1) at 15 u1p3pswitch u1p3p.sh
2022-01-04 11:28:27: automatische Umschaltung aktiv (LV1) at 14 u1p3pswitch u1p3p.sh
2022-01-04 11:28:26: chargestatlp1 0	chargestatlp2 0	chargestatlp3 0 (LV1) at 1284 loadvars loadvars.sh
2022-01-04 11:28:26: plugstatlp1 0	plugstatlp2 0	plugstatlp3 0 (LV1) at 1283 loadvars loadvars.sh
2022-01-04 11:28:26: lp1enabled 1	lp2enabled 1	lp3enabled 1 (LV1) at 1282 loadvars loadvars.sh
2022-01-04 11:28:26: EVU 1:V/1A 2: V/1A 3: V/0A (LV1) at 1281 loadvars loadvars.sh

[HSC]

Sind bei Dir beide Messungen (Huawei + EVU-Kit) am Netzanschluss verbaut?
VG

[evchab]

ja - hatte die openWB schon vor der Huawei PV Anlage

[HSC]

Aah, OK

Daten kommen ja im Status, nur die Umrechnung passt wohl eben nicht.
Wird schon....

[evchab]

m.e. kann das nicht nur an der Umrechnung liegen es kommt ja auch kein Überschuss

249er:

[HSC]

Im Status sieht man welchen:

Code: Alles auswählen

 root: EVU Bezug: 42959937940393190
2022-01-04 11:28:36: PID: 26236: root: EVU Watt: 1.1421983782711584e-42 

Schau Mal am Zähler, ob die Zahlenfolge ca. passt.
VG

[evchab]

nein am Zähler stehen andere Zahlen
imp 10002,9
Exp 8555,7

[HSC]

Imp und Exp sind die kWh als Zähler.
Watt steht für die aktuellen Bezugs- /Einspeiseleistung (pos oder neg).

Verändern sich die Werte nach einer gewissen Zeit im Status oder stehen da eingefrorene Werte?
VG

[evchab]

die Daten ändern sich aber nicht plausibel - halt irgendwie
ab und zu sehen die Daten dann auch mal ganz anders aus - aber quasi nur für einen Zyklus?

nach Neuinstallation und Neustart sieht es erst mal irgendwie normal aus ?!?
sehr schwierig einzuordnen

jetzt kommt ein anderer Fehler im Debug Log

Code: Alles auswählen

modules.common.fault_state.FaultState: ("modules.common.store._util <class 'ValueError'> cannot convert float NaN to integer", <FaultStateLevel.ERROR: 2>)
    raise FaultState.error(__name__+" "+str(type(e))+" "+str(e)) from e
  File "/var/www/html/openWB/packages/modules/common/store/_util.py", line 16, in process_error
    process_error(e)
  File "/var/www/html/openWB/packages/modules/common/store/_counter.py", line 24, in set
    self.__store.set(counter_state)
  File "/var/www/html/openWB/packages/modules/openwb_flex/counter.py", line 83, in update
    self._components[component].update()
  File "/var/www/html/openWB/packages/modules/openwb/device.py", line 48, in update
Traceback (most recent call last):

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

ValueError: cannot convert float NaN to integer
    return str(int(value))
  File "/var/www/html/openWB/packages/modules/common/store/ramdisk/files.py", line 16, in _encode_int
    ramdisk_write(self.filename, self.coder.encoder(value))
  File "/var/www/html/openWB/packages/modules/common/store/ramdisk/files.py", line 33, in write
    self[index].write(value)
  File "/var/www/html/openWB/packages/modules/common/store/ramdisk/files.py", line 56, in write
    files.evu.powers_import.write(counter_state.powers)
  File "/var/www/html/openWB/packages/modules/common/store/_counter.py", line 14, in set
Traceback (most recent call last):
2022-01-04 13:11:06: PID: 12039: root: EVU-Kit: FaultState FaultStateLevel.ERROR, FaultStr modules.common.store._util <class 'ValueError'> cannot convert float NaN to integer, Traceback: 
2022-01-04 13:10:50: **** Regulation loop needs 0 seconds (LV0)
2022-01-04 13:10:46: **** Regulation loop needs 6 seconds (LV0)

[HSC]

....

jetzt kommt ein anderer Fehler im Debug Log

Kommt jetzt nur noch dieser Fehler?
Wenn ja, waren oben nach Umstellung auf EVU- Kit noch irgendwelche anderen Einträge im Hintergrund..
Wenn nein, stelle bitte die Daten kompletter ein, damit Lena / Lutz mehr damit anfangen können (ein kompletter Durchlauf).
VG